container-test-run-container
checks.aarch64-linux.nixos-test-container
· build #513
· 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)13machine1: Waiting for journal at /build/vm-state-machine1/var/log/journal...14machine2: systemd-nspawn running (pid 53)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(machine1): TAP vde-tap1 not found; container will be isolated from VDE19nixos-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.20nixos-nspawn(machine2): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.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 machine1 on /build/vm-state-machine1.25░ Spawning container machine2 on /build/vm-state-machine2.26machine2 # [6900369.243201] machine2 systemd-journald[54]: Journal started27machine2 # [6900369.243258] machine2 systemd-journald[54]: Runtime Journal (/run/log/journal/88542150955243bca8f4b421728e3b18) is 8M, max 2.5G, 2.4G free.28machine2 # [6900369.249562] machine2 systemd[1]: Starting Flush Journal to Persistent Storage...29machine2 # [6900369.250207] machine2 systemd[1]: Starting Create Static Device Nodes in /dev...30machine1 # [6900369.233351] machine1 systemd-journald[54]: Journal started31machine2 # [6900369.260940] machine2 systemd-journald[54]: Time spent on flushing to /var/log/journal/88542150955243bca8f4b421728e3b18 is 1.493ms for 4 entries.32machine1 # [6900369.233406] machine1 systemd-journald[54]: Runtime Journal (/run/log/journal/90e1b78ef8a646d696c2bfcdc6df1e77) is 8M, max 2.5G, 2.4G free.33machine2 # [6900369.260940] machine2 systemd-journald[54]: System Journal (/var/log/journal/88542150955243bca8f4b421728e3b18) is 8M, max 4G, 3.9G free.34machine1 # [6900369.236635] machine1 systemd[1]: Starting Flush Journal to Persistent Storage...35machine2 # [6900369.266910] machine2 systemd[1]: Finished Create Static Device Nodes in /dev.36machine1 # [6900369.237435] machine1 systemd[1]: Starting Create Static Device Nodes in /dev...37machine2 # [6900369.267142] machine2 systemd[1]: Reached target Preparation for Local File Systems.38machine1 # [6900369.247565] machine1 systemd-journald[54]: Time spent on flushing to /var/log/journal/90e1b78ef8a646d696c2bfcdc6df1e77 is 1.259ms for 4 entries.39machine2 # [6900369.267221] machine2 systemd[1]: Reached target Local File Systems.40machine1 # [6900369.247565] machine1 systemd-journald[54]: System Journal (/var/log/journal/90e1b78ef8a646d696c2bfcdc6df1e77) is 8M, max 4G, 3.9G free.41machine2 # [6900369.267931] machine2 systemd[1]: Listening on Boot Loader Control Service Socket.42machine1 # [6900369.253108] machine1 systemd[1]: Finished Create Static Device Nodes in /dev.43machine2 # [6900369.267971] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container44machine1 # [6900369.253359] machine1 systemd[1]: Reached target Preparation for Local File Systems.45machine2 # [6900369.268895] machine2 systemd[1]: Starting Save Transient machine-id to Disk...46machine1 # [6900369.253442] machine1 systemd[1]: Reached target Local File Systems.47machine2 # [6900369.268931] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys48machine1 # [6900369.254154] machine1 systemd[1]: Listening on Boot Loader Control Service Socket.49machine2 # [6900369.269367] machine2 systemd[1]: Finished Flush Journal to Persistent Storage.50machine1 # [6900369.254195] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container51machine2 # [6900369.270559] machine2 systemd[1]: Starting Create System Files and Directories...52machine1 # [6900369.255122] machine1 systemd[1]: Starting Save Transient machine-id to Disk...53machine2 # [6900369.284637] machine2 systemd-tmpfiles[91]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted54machine1 # [6900369.255155] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys55machine2 # [6900369.284832] machine2 systemd-tmpfiles[91]: fchmod() of /var/log/journal failed: Operation not permitted56machine1 # [6900369.261126] machine1 systemd[1]: Finished Flush Journal to Persistent Storage.57machine2 # [6900369.284967] machine2 systemd-tmpfiles[91]: fchmod() of /var/log/journal/88542150955243bca8f4b421728e3b18 failed: Operation not permitted58machine1 # [6900369.262522] machine1 systemd[1]: Starting Create System Files and Directories...59machine2 # [6900369.285172] machine2 systemd-tmpfiles[91]: fchmod() of /run/log/journal failed: Operation not permitted60machine1 # [6900369.284370] machine1 systemd-tmpfiles[91]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted61machine2 # [6900369.287968] machine2 systemd[1]: Finished Create System Files and Directories.62machine1 # [6900369.284616] machine1 systemd-tmpfiles[91]: fchmod() of /var/log/journal failed: Operation not permitted63machine2 # [6900369.289019] machine2 systemd[1]: Starting Rebuild Journal Catalog...64machine1 # [6900369.284778] machine1 systemd-tmpfiles[91]: fchmod() of /var/log/journal/90e1b78ef8a646d696c2bfcdc6df1e77 failed: Operation not permitted65machine2 # [6900369.289696] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...66machine1 # [6900369.285025] machine1 systemd-tmpfiles[91]: fchmod() of /run/log/journal failed: Operation not permitted67machine2 # [6900369.301126] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.68machine1 # [6900369.287626] machine1 systemd[1]: Finished Create System Files and Directories.69machine2 # [6900369.309365] machine2 systemd[1]: Finished Rebuild Journal Catalog.70machine1 # [6900369.288749] machine1 systemd[1]: Starting Rebuild Journal Catalog...71machine2 # [6900369.310670] machine2 systemd[1]: Starting Update is Completed...72machine1 # [6900369.289479] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...73machine2 # [6900369.314472] machine2 systemd[1]: Finished Save Transient machine-id to Disk.74machine1 # [6900369.300559] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.75machine2 # [6900369.320043] machine2 systemd[1]: Finished Update is Completed.76machine1 # [6900369.310648] machine1 systemd[1]: Finished Rebuild Journal Catalog.77machine2 # [6900369.320211] machine2 systemd[1]: Reached target System Initialization.78machine1 # [6900369.311659] machine1 systemd[1]: Starting Update is Completed...79machine2 # [6900369.320282] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container80machine1 # [6900369.314031] machine1 systemd[1]: Finished Save Transient machine-id to Disk.81machine2 # [6900369.320319] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories.82machine1 # [6900369.322323] machine1 systemd[1]: Finished Update is Completed.83machine2 # [6900369.320336] machine2 systemd[1]: Reached target Timer Units.84machine1 # [6900369.322480] machine1 systemd[1]: Reached target System Initialization.85machine2 # [6900369.320444] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket.86machine1 # [6900369.322555] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container87machine2 # [6900369.320552] machine2 systemd[1]: Listening on Nix Daemon Socket.88machine1 # [6900369.322594] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories.89machine2 # [6900369.321525] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).90machine1 # [6900369.322611] machine1 systemd[1]: Reached target Timer Units.91machine2 # [6900369.322355] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).92machine1 # [6900369.322713] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket.93machine2 # [6900369.322447] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.94machine1 # [6900369.322838] machine1 systemd[1]: Listening on Nix Daemon Socket.95machine2 # [6900369.322467] machine2 systemd[1]: Reached target Socket Units.96machine1 # [6900369.323806] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).97machine2 # [6900369.322502] machine2 systemd[1]: Reached target Basic System.98machine1 # [6900369.324676] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).99machine2 # [6900369.323452] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database...100machine1 # [6900369.324776] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.101machine2 # [6900369.324280] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...102machine1 # [6900369.324795] machine1 systemd[1]: Reached target Socket Units.103machine2 # [6900369.324958] machine2 systemd[1]: Starting SSH Host Keys Generation...104machine1 # [6900369.324834] machine1 systemd[1]: Reached target Basic System.105machine2 # [6900369.341844] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database.106machine1 # [6900369.325764] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database...107machine2 # [6900369.386559] machine2 systemd[1]: Finished Firewall.108machine1 # [6900369.326500] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...109machine1 # [6900369.327399] machine1 systemd[1]: Starting SSH Host Keys Generation...110machine1 # [6900369.342754] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database.111machine2 # [6900369.386697] machine2 systemd[1]: Reached target Preparation for Network.112machine1 # [6900369.380172] machine1 systemd[1]: Finished Firewall.113machine2 # [6900369.387464] machine2 systemd[1]: Starting Address configuration of eth1...114machine1 # [6900369.380314] machine1 systemd[1]: Reached target Preparation for Network.115machine2 # [6900369.388266] machine2 systemd[1]: Starting Extra networking commands....116machine1 # [6900369.381160] machine1 systemd[1]: Starting Address configuration of eth1...117machine2 # [6900369.404736] machine2 network-addresses-eth1-start[179]: adding address 192.168.1.2/24... done118machine1 # [6900369.381922] machine1 systemd[1]: Starting Extra networking commands....119machine2 # [6900369.407333] machine2 network-addresses-eth1-start[179]: adding address 2001:db8:1::2/64... done120machine1 # [6900369.399526] machine1 network-addresses-eth1-start[179]: adding address 192.168.1.1/24... done121machine2 # [6900369.411444] machine2 systemd[1]: Finished Address configuration of eth1.122machine1 # [6900369.402432] machine1 network-addresses-eth1-start[179]: adding address 2001:db8:1::1/64... done123machine2 # [6900369.415048] machine2 nsncd[130]: Aug 27 20:09:55.468 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"124machine1 # [6900369.406316] machine1 systemd[1]: Finished Address configuration of eth1.125machine2 # [6900369.415198] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd).126machine1 # [6900369.425588] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd).127machine2 # [6900369.415290] machine2 systemd[1]: Reached target Host and Network Name Lookups.128machine1 # [6900369.425973] machine1 nsncd[135]: Aug 27 20:09:55.478 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"129machine2 # [6900369.415380] machine2 systemd[1]: Reached target User and Group Name Lookups.130machine1 # [6900369.425666] machine1 systemd[1]: Reached target Host and Network Name Lookups.131machine2 # [6900369.417240] machine2 systemd[1]: Starting User Login Management...132machine1 # [6900369.425838] machine1 systemd[1]: Reached target User and Group Name Lookups.133machine2 # [6900369.443209] machine2 systemd[1]: Stopped target Host and Network Name Lookups.134machine1 # [6900369.427504] machine1 systemd[1]: Starting User Login Management...135machine2 # [6900369.443264] machine2 systemd[1]: Stopping Host and Network Name Lookups...136machine1 # [6900369.440216] machine1 systemd[1]: Stopped target Host and Network Name Lookups.137machine2 # [6900369.443303] machine2 systemd[1]: Stopped target User and Group Name Lookups.138machine1 # [6900369.440274] machine1 systemd[1]: Stopping Host and Network Name Lookups...139machine2 # [6900369.443327] machine2 systemd[1]: Stopping User and Group Name Lookups...140machine1 # [6900369.440318] machine1 systemd[1]: Stopped target User and Group Name Lookups.141machine2 # [6900369.443479] machine2 systemd[1]: Stopping Name Service Cache Daemon (nsncd)...142machine1 # [6900369.440340] machine1 systemd[1]: Stopping User and Group Name Lookups...143machine2 # [6900369.447677] machine2 systemd[1]: nscd.service: Deactivated successfully.144machine1 # [6900369.440490] machine1 systemd[1]: Stopping Name Service Cache Daemon (nsncd)...145machine2 # [6900369.447856] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd).146machine1 # [6900369.448619] machine1 systemd[1]: nscd.service: Deactivated successfully.147machine2 # [6900369.450166] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...148machine1 # [6900369.448693] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd).149machine2 # [6900369.459101] machine2 systemd[1]: Finished Extra networking commands..150machine1 # [6900369.451359] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...151machine2 # [6900369.459260] machine2 systemd[1]: Reached target Network.152machine1 # [6900369.456050] machine1 systemd[1]: Finished Extra networking commands..153machine1 # [6900369.456766] machine1 systemd[1]: Reached target Network.154machine2 # [6900369.527394] machine2 nsncd[255]: Aug 27 20:09:55.580 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"155machine2 # [6900369.527435] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd).156machine2 # [6900369.527497] machine2 systemd[1]: Reached target Host and Network Name Lookups.157machine2 # [6900369.527557] machine2 systemd[1]: Reached target User and Group Name Lookups.158machine2 # [6900369.528413] machine2 systemd[1]: Starting Permit User Sessions...159machine2 # [6900369.575377] machine2 systemd[1]: Finished Permit User Sessions.160machine2 # [6900369.577289] machine2 systemd[1]: Started Console Getty.161machine2 # [6900369.577371] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0162machine2 # [6900369.577421] machine2 systemd[1]: Reached target Login Prompts.163machine1 # [6900369.549104] machine1 nsncd[259]: Aug 27 20:09:55.602 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"164machine1 # [6900369.549202] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd).165machine1 # [6900369.549316] machine1 systemd[1]: Reached target Host and Network Name Lookups.166machine1 # [6900369.549437] machine1 systemd[1]: Reached target User and Group Name Lookups.167machine1 # [6900369.568807] machine1 systemd[1]: Starting Permit User Sessions...168machine1 # [6900369.580525] machine1 systemd[1]: Finished Permit User Sessions.169machine1 # [6900369.581654] machine1 systemd[1]: Started Console Getty.170machine1 # [6900369.581694] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0171machine1 # [6900369.581712] machine1 systemd[1]: Reached target Login Prompts.172machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: Generating public/private rsa key pair.173machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key174machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub175machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: The key fingerprint is:176machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: SHA256:unQms7yqUpbcsUdvofyy7gPNwnzpv/oEep6KPE6WCw8 root@machine1177machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: The key's randomart image is:178machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: +---[RSA 4096]----+179machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: | |180machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: | |181machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: | |182machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: | . . . |183machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: | . = B.+S. |184machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: | =.B.Ooo |185machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: |Eo+ .**o+ |186machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: |.B.o =+X. |187machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: | oBoo=%O+. |188machine1 # [6900369.827745] machine1 sshd-keygen-start[158]: +----[SHA256]-----+189machine1 # [6900369.846274] machine1 systemd-logind[230]: New seat seat0.190machine1 # [6900369.846617] machine1 systemd[1]: Started User Login Management.191machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: Generating public/private ed25519 key pair.192machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key193machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub194machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: The key fingerprint is:195machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: SHA256:jmHX198HMEC+KrVHONPloCVD8qf8mRCtSxtSmej2l1I root@machine1196machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: The key's randomart image is:197machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: +--[ED25519 256]--+198machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | . ..o |199machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | = = . |200machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | . O * + |201machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | . o & = + |202machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | * S E o o |203machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | o X & = o.|204machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | o O B +|205machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | . + .|206machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: | |207machine1 # [6900369.847583] machine1 sshd-keygen-start[281]: +----[SHA256]-----+208machine1 # [6900369.848508] machine1 systemd[1]: Starting D-Bus System Message Bus...209machine1 # [6900369.849814] machine1 systemd[1]: Starting linger-users.service...210machine1 # [6900369.850245] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully.211machine1 # [6900369.850493] machine1 systemd[1]: Finished SSH Host Keys Generation.212machine1 # [6900369.850585] machine1 systemd[1]: sshd-keygen.service: Consumed 1.037s CPU time over 522ms wall clock time.213machine1 # [6900369.852945] machine1 systemd[1]: Starting SSH Daemon...214machine1 # [6900369.910441] machine1 systemd[1]: linger-users.service: Deactivated successfully.215machine1 # [6900369.910526] machine1 sshd[285]: Server listening on 0.0.0.0 port 22.216machine1 # [6900369.910549] machine1 sshd[285]: Server listening on :: port 22.217machine1 # [6900369.910744] machine1 systemd[1]: Finished linger-users.service.218machine1 # [6900369.911125] machine1 systemd[1]: Started SSH Daemon.219machine1 # [6900369.911364] machine1 systemd[1]: Reached target Multi-User System.220machine1 # [6900370.071287] machine1 dbus-broker-launch[282]: Looking up NSS user entry for 'systemd-timesync'...221machine1 # [6900370.072139] machine1 dbus-broker-launch[282]: NSS returned no entry for 'systemd-timesync'222machine1 # [6900370.072139] machine1 dbus-broker-launch[282]: Invalid user-name in /nix/store/giz3li8apb3pvmikksynv3kdqgp6s3nz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"223machine1 # [6900370.072527] machine1 systemd[1]: Started D-Bus System Message Bus.224machine1 # [6900370.072850] machine1 systemd[1]: Startup finished in 1.211s.225machine1 # [6900370.080708] machine1 dbus-broker-launch[282]: Ready226machine2 # [6900369.853229] machine2 systemd-logind[215]: New seat seat0.227machine2 # [6900369.853604] machine2 systemd[1]: Started User Login Management.228machine2 # [6900369.901063] machine2 systemd[1]: Starting D-Bus System Message Bus...229machine2 # [6900369.902802] machine2 systemd[1]: Starting linger-users.service...230machine2 # [6900369.918749] machine2 systemd[1]: linger-users.service: Deactivated successfully.231machine2 # [6900369.919000] machine2 systemd[1]: Finished linger-users.service.232machine2 # [6900370.052504] machine2 dbus-broker-launch[276]: Looking up NSS user entry for 'systemd-timesync'...233machine2 # [6900370.053717] machine2 dbus-broker-launch[276]: NSS returned no entry for 'systemd-timesync'234machine2 # [6900370.053717] machine2 dbus-broker-launch[276]: Invalid user-name in /nix/store/giz3li8apb3pvmikksynv3kdqgp6s3nz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"235machine2 # [6900370.054207] machine2 systemd[1]: Started D-Bus System Message Bus.236machine2 # [6900370.061515] machine2 dbus-broker-launch[276]: Ready237machine1: (finished: must succeed: systemctl status sshd, in 1.66 seconds)238machine2: must succeed: systemctl status sshd239machine2: (finished: must succeed: systemctl status sshd, in 0.33 seconds)240machine1: waiting for unit sshd241machine1: (finished: waiting for unit sshd, in 0.02 seconds)242machine2: waiting for unit sshd243machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: Generating public/private rsa key pair.244machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key245machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub246machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: The key fingerprint is:247machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: SHA256:9SvT9//AEdBMZv3uOw0DDIuHbF9tRldz25hxZa3DWcs root@machine2248machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: The key's randomart image is:249machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: +---[RSA 4096]----+250machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | .+=oX|251machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | . ++*X|252machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | . o.+ +=*+|253machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | =.o.+ OE.|254machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | .So ..+.o |255machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | .. oo..|256machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | o o ++.|257machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | o . +o|258machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: | .O|259machine2 # [6900370.219522] machine2 sshd-keygen-start[152]: +----[SHA256]-----+260machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: Generating public/private ed25519 key pair.261machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key262machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub263machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: The key fingerprint is:264machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: SHA256:qppthNuu1Bd6gYpGau54aR7JXPZbexjOx79ZU0uHLxs root@machine2265machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: The key's randomart image is:266machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: +--[ED25519 256]--+267machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: | |268machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: | |269machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: | |270machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: | . . |271machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: | . .+ o S ..o|272machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: |o+.*.o +. .oo|273machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: |ooB=o =o.+ E..|274machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: |=.=+o+ o+.+ o = |275machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: |o*==+ . .o .+.. |276machine2 # [6900370.235059] machine2 sshd-keygen-start[286]: +----[SHA256]-----+277machine2 # [6900370.235589] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.278machine2 # [6900370.237364] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully.279machine2 # [6900370.237479] machine2 systemd[1]: Finished SSH Host Keys Generation.280machine2 # [6900370.239664] machine2 systemd[1]: Starting SSH Daemon...281machine2 # [6900370.372122] machine2 sshd[288]: Server listening on 0.0.0.0 port 22.282machine2 # [6900370.372152] machine2 sshd[288]: Server listening on :: port 22.283machine2 # [6900370.372332] machine2 systemd[1]: Started SSH Daemon.284machine2 # [6900370.372637] machine2 systemd[1]: Reached target Multi-User System.285machine2 # [6900370.372868] machine2 systemd[1]: Startup finished in 1.513s.286machine2: (finished: waiting for unit sshd, in 0.02 seconds)287machine1 # [6900370.223536] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.288machine1: must succeed: ip addr show eth1289machine1: (finished: must succeed: ip addr show eth1, in 0.01 seconds)290machine2: must succeed: ip addr show eth1291machine2: (finished: must succeed: ip addr show eth1, in 0.01 seconds)292machine1: must succeed: ping -c 1 machine2293machine1: (finished: must succeed: ping -c 1 machine2, in 0.01 seconds)294(finished: run the VM test script, in 2.05 seconds)295test script finished in 2.21s296cleanup297kill NspawnMachine (pid 52)298kill NspawnMachine (pid 53)299Container machine1 terminated by signal KILL.300Container machine2 terminated by signal KILL.301(finished: cleanup, in 0.38 seconds)