container-test-run-sshd
checks.aarch64-linux.sshd
· build #1080
· raw
1build01: building mxlyfif7yhybds305h5m052xf7zm3hlm-container-test-run-sshd.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Initializing machine ID from random generator.6Test will time out and terminate in 3600.0 seconds7run the VM test script8additionally exposed symbols:9 client, server,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_ssh12??? Warning (SyntaxWarning): "\*" is an invalid escape sequence. Such sequences will not work in the future. Did you mean "\\*"? A raw string is also an option.13 File "<string>", line 714??? Warning (SyntaxWarning): "\*" is an invalid escape sequence. Such sequences will not work in the future. Did you mean "\\*"? A raw string is also an option.15 File "<string>", line 1316start all VMs17server: systemd-nspawn running (pid 53)18client: systemd-nspawn running (pid 52)19server: Waiting for journal at /build/vm-state-server/var/log/journal...20client: Waiting for journal at /build/vm-state-client/var/log/journal...21(finished: start all VMs, in 0.00 seconds)22server: must succeed: /nix/store/1553icvwp9ryqw3hng22rrah30jw9pn0-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 2223nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE24nixos-nspawn(server): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.25nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE26nixos-nspawn(client): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.27Note: 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.28░ Spawning container server on /build/vm-state-server.29Note: 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.30░ Spawning container client on /build/vm-state-client.31server # No journal files were found.32server # No journal boot entry found for the specified boot (+0).33client # No journal files were found.34client # No journal boot entry found for the specified boot (+0).35client # [524387.951579] client systemd-journald[73]: Journal started36server # [524387.951438] server systemd-journald[101]: Journal started37client # [524387.951632] client systemd-journald[73]: Runtime Journal (/run/log/journal/1bebf94f1ee24a6894652b2d6631bb9c) is 8M, max 2.5G, 2.4G free.38server # [524387.951508] server systemd-journald[101]: Runtime Journal (/run/log/journal/3a064e8d4e704acfa87b228edcd00fb1) is 8M, max 2.5G, 2.4G free.39client # [524388.118876] client systemd[1]: Finished Apply Kernel Variables.40client # [524388.144645] client systemd[1]: Finished Firewall.41server # [524387.997151] server systemd[1]: Finished Apply Kernel Variables.42client # [524388.145131] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.43server # [524388.118882] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.44server # [524388.154117] server systemd[1]: Finished Firewall.45client # [524388.176726] client systemd[1]: Reached target Preparation for Network.46server # [524388.156498] server systemd[1]: Reached target Preparation for Network.47server # [524388.157086] server systemd[1]: Listening on Network Management Resolve Hook Socket.48client # [524388.177306] client systemd[1]: Listening on Network Management Resolve Hook Socket.49client # [524388.178236] client systemd[1]: Starting Flush Journal to Persistent Storage...50client # [524388.179093] client systemd[1]: Starting Network Name Resolution...51server # [524388.158247] server systemd[1]: Starting Flush Journal to Persistent Storage...52client # [524388.179733] client systemd[1]: Starting Create Static Device Nodes in /dev...53server # [524388.159899] server systemd[1]: Starting Network Name Resolution...54client # [524388.188708] client systemd-journald[73]: Time spent on flushing to /var/log/journal/1bebf94f1ee24a6894652b2d6631bb9c is 3.639ms for 10 entries.55server # [524388.160875] server systemd[1]: Starting Create Static Device Nodes in /dev...56server # [524388.170602] server systemd-journald[101]: Time spent on flushing to /var/log/journal/3a064e8d4e704acfa87b228edcd00fb1 is 1.690ms for 10 entries.57client # [524388.188708] client systemd-journald[73]: System Journal (/var/log/journal/1bebf94f1ee24a6894652b2d6631bb9c) is 8M, max 4G, 3.9G free.58server # [524388.170602] server systemd-journald[101]: System Journal (/var/log/journal/3a064e8d4e704acfa87b228edcd00fb1) is 8M, max 4G, 3.9G free.59server # [524388.196091] server systemd[1]: Finished Flush Journal to Persistent Storage.60server # [524388.204277] server systemd[1]: Finished Create Static Device Nodes in /dev.61client # [524388.246682] client systemd[1]: Finished Flush Journal to Persistent Storage.62server # [524388.205522] server systemd[1]: Reached target Preparation for Local File Systems.63server # [524388.205635] server systemd[1]: Reached target Local File Systems.64server # [524388.206399] server systemd[1]: Listening on Boot Loader Control Service Socket.65server # [524388.206448] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container66server # [524388.207400] server systemd[1]: Starting Save Transient machine-id to Disk...67server # [524388.208212] server systemd[1]: Starting Create System Files and Directories...68server # [524388.208247] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys69server # [524388.209269] server systemd[1]: Starting Network Management...70server # [524388.224161] server systemd-tmpfiles[208]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted71server # [524388.224345] server systemd-tmpfiles[208]: fchmod() of /var/log/journal failed: Operation not permitted72server # [524388.224459] server systemd-tmpfiles[208]: fchmod() of /var/log/journal/3a064e8d4e704acfa87b228edcd00fb1 failed: Operation not permitted73server # [524388.224635] server systemd-tmpfiles[208]: fchmod() of /run/log/journal failed: Operation not permitted74server # [524388.226694] server systemd[1]: Finished Create System Files and Directories.75server # [524388.227921] server systemd[1]: Starting Rebuild Journal Catalog...76server # [524388.228731] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...77server # [524388.242326] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.78server # [524388.249262] server systemd[1]: Finished Rebuild Journal Catalog.79server # [524388.250463] server systemd[1]: Starting Update is Completed...80server # [524388.263156] server systemd[1]: Finished Update is Completed.81server # [524388.343027] server systemd[1]: Finished Save Transient machine-id to Disk.82server # [524388.946237] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.83client # [524388.247182] client systemd[1]: Finished Create Static Device Nodes in /dev.84client # [524388.248452] client systemd[1]: Reached target Preparation for Local File Systems.85client # [524388.248558] client systemd[1]: Reached target Local File Systems.86client # [524388.249336] client systemd[1]: Listening on Boot Loader Control Service Socket.87client # [524388.249391] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container88client # [524388.250297] client systemd[1]: Starting Save Transient machine-id to Disk...89client # [524388.251051] client systemd[1]: Starting Create System Files and Directories...90client # [524388.251136] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys91client # [524388.252131] client systemd[1]: Starting Network Management...92client # [524388.268882] client systemd-tmpfiles[178]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted93client # [524388.269038] client systemd-tmpfiles[178]: fchmod() of /var/log/journal failed: Operation not permitted94client # [524388.269141] client systemd-tmpfiles[178]: fchmod() of /var/log/journal/1bebf94f1ee24a6894652b2d6631bb9c failed: Operation not permitted95client # [524388.269292] client systemd-tmpfiles[178]: fchmod() of /run/log/journal failed: Operation not permitted96client # [524388.276252] client systemd[1]: Finished Create System Files and Directories.97client # [524388.277432] client systemd[1]: Starting Rebuild Journal Catalog...98client # [524388.278203] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...99client # [524388.290660] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.100client # [524388.297984] client systemd[1]: Finished Rebuild Journal Catalog.101client # [524388.299414] client systemd[1]: Starting Update is Completed...102client # [524388.311100] client systemd[1]: Finished Update is Completed.103client # [524388.339894] client systemd[1]: Finished Save Transient machine-id to Disk.104client # [524388.946390] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.105client # [524389.487869] client systemd-networkd[179]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted106client # [524389.487970] client systemd-networkd[179]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted107client # [524389.518503] client systemd-networkd[179]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.108client # [524389.518668] client systemd-networkd[179]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.109client # [524389.518894] client systemd-networkd[179]: lo: Link UP110client # [524389.518896] client systemd-networkd[179]: lo: Gained carrier111client # [524389.519082] client systemd-networkd[179]: eth1: Configuring with /etc/systemd/network/40-eth1.network.112client # [524389.519623] client systemd[1]: Started Network Management.113client # [524389.519722] client systemd-networkd[179]: eth1: Link UP114client # [524389.520139] client systemd-networkd[179]: eth1: Gained carrier115client # [524389.520834] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...116client # [524389.558226] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.117server # [524389.488053] server systemd-networkd[209]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted118server # [524389.488143] server systemd-networkd[209]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted119server # [524389.518445] server systemd-networkd[209]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.120server # [524389.518612] server systemd-networkd[209]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.121server # [524389.518832] server systemd-networkd[209]: lo: Link UP122server # [524389.518838] server systemd-networkd[209]: lo: Gained carrier123server # [524389.519069] server systemd-networkd[209]: eth1: Configuring with /etc/systemd/network/40-eth1.network.124server # [524389.519637] server systemd-networkd[209]: eth1: Link UP125server # [524389.520268] server systemd[1]: Started Network Management.126server # [524389.520798] server systemd-networkd[209]: eth1: Gained carrier127server # [524389.521315] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...128server # [524389.562409] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.129server # [524390.465482] server systemd-resolved[201]: Positive Trust Anchors:130server # [524390.465502] server systemd-resolved[201]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d131server # [524390.465505] server systemd-resolved[201]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16132server # [524390.465541] server systemd-resolved[201]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test133server # [524390.466515] server systemd-resolved[201]: Using system hostname 'server'.134server # [524390.468380] server systemd[1]: Started Network Name Resolution.135server # [524390.468482] server systemd[1]: Reached target Network.136server # [524390.468554] server systemd[1]: Reached target System Initialization.137server # [524390.468618] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container138server # [524390.468643] server systemd[1]: Started Daily Cleanup of Temporary Directories.139server # [524390.468661] server systemd[1]: Reached target Timer Units.140server # [524390.468799] server systemd[1]: Listening on D-Bus System Message Bus Socket.141server # [524390.468926] server systemd[1]: Listening on Nix Daemon Socket.142server # [524390.469785] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).143server # [524390.470510] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).144server # [524390.471031] server systemd[1]: Listening on SSH Socket.145server # [524390.471177] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.146server # [524390.471198] server systemd[1]: Reached target Socket Units.147server # [524390.471254] server systemd[1]: Reached target Basic System.148server # [524390.472679] server systemd[1]: Starting Import lastlog data into lastlog2 database...149server # [524390.473840] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...150server # [524390.473877] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.151server # [524390.475852] server systemd[1]: Starting D-Bus System Message Bus...152server # [524390.494625] server systemd[1]: Finished Import lastlog data into lastlog2 database.153client # [524390.468451] client systemd-resolved[171]: Positive Trust Anchors:154client # [524390.468470] client systemd-resolved[171]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d155client # [524390.468474] client systemd-resolved[171]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16156client # [524390.468509] client systemd-resolved[171]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test157client # [524390.469255] client systemd-resolved[171]: Using system hostname 'client'.158client # [524390.471239] client systemd[1]: Started Network Name Resolution.159client # [524390.471335] client systemd[1]: Reached target Network.160client # [524390.471406] client systemd[1]: Reached target System Initialization.161client # [524390.471460] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container162client # [524390.471492] client systemd[1]: Started Daily Cleanup of Temporary Directories.163client # [524390.471512] client systemd[1]: Reached target Timer Units.164client # [524390.471640] client systemd[1]: Listening on D-Bus System Message Bus Socket.165client # [524390.471786] client systemd[1]: Listening on Nix Daemon Socket.166client # [524390.471912] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.167client # [524390.471936] client systemd[1]: Reached target Socket Units.168client # [524390.471975] client systemd[1]: Reached target Basic System.169client # [524390.474013] client systemd[1]: Starting Import lastlog data into lastlog2 database...170client # [524390.475332] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...171client # [524390.477115] client systemd[1]: Starting D-Bus System Message Bus...172client # [524390.494537] client systemd[1]: Finished Import lastlog data into lastlog2 database.173client # [524390.736932] client systemd[1]: Started Name Service Cache Daemon (nsncd).174client # [524390.737121] client nsncd[193]: Oct 22 11:03:15.708 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"175client # [524390.737012] client systemd[1]: Reached target Host and Network Name Lookups.176client # [524390.737073] client systemd[1]: Reached target User and Group Name Lookups.177client # [524390.780457] client systemd[1]: Starting User Login Management...178client # [524390.781366] client systemd[1]: Starting Permit User Sessions...179client # [524390.791961] client systemd[1]: Finished Permit User Sessions.180client # [524390.793048] client systemd[1]: Started Console Getty.181client # [524390.793089] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0182client # [524390.793108] client systemd[1]: Reached target Login Prompts.183server # [524390.727066] server nsncd[223]: Oct 22 11:03:15.698 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"184client # [524390.848232] client systemd-networkd[179]: eth1: Gained IPv6LL185server # [524390.727083] server systemd[1]: Started Name Service Cache Daemon (nsncd).186server # [524390.727157] server systemd[1]: Reached target Host and Network Name Lookups.187client # [524390.948785] client dbus-broker-launch[194]: Looking up NSS user entry for 'systemd-timesync'...188server # [524390.727224] server systemd[1]: Reached target User and Group Name Lookups.189client # [524390.949457] client dbus-broker-launch[194]: NSS returned no entry for 'systemd-timesync'190server # [524390.728932] server systemd[1]: Starting User Login Management...191client # [524390.949457] client dbus-broker-launch[194]: Invalid user-name in /nix/store/fg5gslm2mdh87d4rgkfkpyb7i3hmbmwa-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"192server # [524390.729814] server systemd[1]: Starting Permit User Sessions...193client # [524390.950014] client systemd[1]: Started D-Bus System Message Bus.194server # [524390.787612] server systemd[1]: Finished Permit User Sessions.195client # [524390.956841] client dbus-broker-launch[194]: Ready196server # [524390.788866] server systemd[1]: Started Console Getty.197server # [524390.788910] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0198server # [524390.788928] server systemd[1]: Reached target Login Prompts.199server # [524391.000533] server dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...200server # [524391.001527] server dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'201server # [524391.001527] server dbus-broker-launch[224]: Invalid user-name in /nix/store/xrz9k5773fcmw896m1svdqzz8ba29waf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"202server # [524391.001979] server systemd[1]: Started D-Bus System Message Bus.203server # [524391.004155] server systemd-networkd[209]: eth1: Gained IPv6LL204server # [524391.009219] server dbus-broker-launch[224]: Ready205server # [524392.621861] server systemd-logind[239]: New seat seat0.206server # [524392.622322] server systemd[1]: Started User Login Management.207server # [524392.623726] server systemd[1]: Starting linger-users.service...208server # [524392.636497] server systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available209server # [524392.636541] server systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available210server # [524392.636572] server systemd[1]: linger-users.service: Deactivated successfully.211server # [524392.636810] server systemd[1]: Finished linger-users.service.212server # [524392.637321] server systemd[1]: Reached target Multi-User System.213server # [524392.637546] server systemd[1]: Startup finished in 5.100s.214client # [524392.631910] client systemd-logind[209]: New seat seat0.215client # [524392.632325] client systemd[1]: Started User Login Management.216client # [524392.633908] client systemd[1]: Starting linger-users.service...217client # [524392.649686] client systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available218client # [524392.649735] client systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available219client # [524392.649759] client systemd[1]: linger-users.service: Deactivated successfully.220client # [524392.650015] client systemd[1]: Finished linger-users.service.221client # [524392.650762] client systemd[1]: Reached target Multi-User System.222client # [524392.651272] client systemd[1]: Startup finished in 5.175s.223Connection to 127.0.0.1 22 port [tcp/ssh] succeeded!224server: (finished: must succeed: /nix/store/1553icvwp9ryqw3hng22rrah30jw9pn0-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 22, in 6.17 seconds)225server: must succeed: grep '^@cert-authority ssh-ca,\*.clan,\*.example.com ssh-ed25519 ' /etc/ssh/ssh_known_hosts226server: (finished: must succeed: grep '^@cert-authority ssh-ca,\*.clan,\*.example.com ssh-ed25519 ' /etc/ssh/ssh_known_hosts, in 0.01 seconds)227server: must succeed: grep '^localhost,server,server.clan ssh-ed25519 ' /etc/ssh/ssh_known_hosts228server: (finished: must succeed: grep '^localhost,server,server.clan ssh-ed25519 ' /etc/ssh/ssh_known_hosts, in 0.01 seconds)229client: must succeed: grep '^.cert-authority ssh-ca,\*.clan,\*.example.com ssh-ed25519 ' /etc/ssh/ssh_known_hosts230client: (finished: must succeed: grep '^.cert-authority ssh-ca,\*.clan,\*.example.com ssh-ed25519 ' /etc/ssh/ssh_known_hosts, in 0.01 seconds)231server: must succeed: grep 'test-key' /etc/ssh/authorized_keys.d/root232server: (finished: must succeed: grep 'test-key' /etc/ssh/authorized_keys.d/root, in 0.01 seconds)233client: must succeed: cp /nix/store/wiksrsbhvksa9njqxd2i7say2bcvibnn-test-key /tmp/test-key && chmod 600 /tmp/test-key234client: (finished: must succeed: cp /nix/store/wiksrsbhvksa9njqxd2i7say2bcvibnn-test-key /tmp/test-key && chmod 600 /tmp/test-key, in 0.01 seconds)235client: must succeed: ssh -o StrictHostKeyChecking=accept-new -i /tmp/test-key root@server 'echo SSH_SUCCESS'236Warning: Permanently added 'server' (ED25519) to the list of known hosts.237client: (finished: must succeed: ssh -o StrictHostKeyChecking=accept-new -i /tmp/test-key root@server 'echo SSH_SUCCESS', in 0.13 seconds)238server: must succeed: grep 'root@server' /etc/ssh/authorized_keys.d/root239server: (finished: must succeed: grep 'root@server' /etc/ssh/authorized_keys.d/root, in 0.01 seconds)240client: must succeed: scp -i /tmp/test-key root@server:/run/secrets/per-machine/server/sshd-root-key/id_ed25519 /tmp/generated-key241server # [524393.112514] server systemd[1]: Created slice Slice /system/sshd.242server # [524393.112609] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.243server # [524393.113854] server systemd[1]: Started SSH per-connection Daemon (127.0.0.1:54686).244server # [524393.159376] server sshd-session[254]: Connection closed by 127.0.0.1 port 54686 [preauth]245server # [524393.160871] server systemd[1]: sshd@0-286738-127.0.0.1:22-127.0.0.1:54686.service: Deactivated successfully.246server # [524393.199880] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.247server # [524393.201190] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:44718).248server # [524393.300778] server sshd-session[262]: Accepted publickey for root from 2001:db8:1::1 port 44718 ssh2: ED25519 SHA256:o/QD6rB73sctZzyYRQeIJAuU+/iqP9mNNwIF4P4uLSc249server # [524393.317531] server sshd-session[266]: Received disconnect from 2001:db8:1::1 port 44718:11: disconnected by user250server # [524393.317807] server sshd-session[266]: Disconnected from user root 2001:db8:1::1 port 44718251server # [524393.319283] server systemd[1]: sshd@1-323594-2001:db8:1::2:22-2001:db8:1::1:44718.service: Deactivated successfully.252server # [524393.340105] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.253server # [524393.341157] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:44730).254client: (finished: must succeed: scp -i /tmp/test-key root@server:/run/secrets/per-machine/server/sshd-root-key/id_ed25519 /tmp/generated-key, in 0.19 seconds)255client: must succeed: chmod 600 /tmp/generated-key256client: (finished: must succeed: chmod 600 /tmp/generated-key, in 0.01 seconds)257client: must succeed: ssh -i /tmp/generated-key root@server 'echo GENERATED_KEY_SUCCESS'258client: (finished: must succeed: ssh -i /tmp/generated-key root@server 'echo GENERATED_KEY_SUCCESS', in 0.16 seconds)259(finished: run the VM test script, in 6.73 seconds)260server # [524393.473061] server sshd-session[270]: Accepted publickey for root from 2001:db8:1::1 port 44730 ssh2: ED25519 SHA256:o/QD6rB73sctZzyYRQeIJAuU+/iqP9mNNwIF4P4uLSc261server # [524393.516949] server sshd-session[274]: Received disconnect from 2001:db8:1::1 port 44730:11: disconnected by user262server # [524393.517121] server sshd-session[274]: Disconnected from user root 2001:db8:1::1 port 44730263server # [524393.518347] server systemd[1]: sshd@2-139277-2001:db8:1::2:22-2001:db8:1::1:44730.service: Deactivated successfully.264server # [524393.542855] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.265server # [524393.543943] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:44736).266server # [524393.673345] server sshd-session[277]: Accepted publickey for root from 2001:db8:1::1 port 44736 ssh2: ED25519 SHA256:MCzrNhWPXrS5cfZuU4VVe/qCRf7rZ9TDwEBJMvjFj3w267server # [524393.691232] server sshd-session[281]: Received disconnect from 2001:db8:1::1 port 44736:11: disconnected by user268server # [524393.691393] server sshd-session[281]: Disconnected from user root 2001:db8:1::1 port 44736269server # [524393.692729] server systemd[1]: sshd@3-65551-2001:db8:1::2:22-2001:db8:1::1:44736.service: Deactivated successfully.270test script finished in 7.16s271cleanup272kill NspawnMachine (pid 52)273kill NspawnMachine (pid 53)274Container client terminated by signal KILL.275(finished: cleanup, in 0.38 seconds)276Container server terminated by signal KILL.