container-test-run-clan-test-p2p-ssh-iroh
checks.aarch64-linux.p2p-ssh-iroh
· build #964
· raw
1build01: building clz7ij9wkbk0izlasqnahpbgzmc332lf-container-test-run-clan-test-p2p-ssh-iroh.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 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_ssh12start all VMs13client: systemd-nspawn running (pid 52)14server: systemd-nspawn running (pid 53)15client: Waiting for journal at /build/vm-state-client/var/log/journal...16server: Waiting for journal at /build/vm-state-server/var/log/journal...17(finished: start all VMs, in 0.00 seconds)18server: waiting for success: /nix/store/95cdzm5yr43vsmnzksqwby90jxkp8z3q-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 2219nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE20nixos-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.21nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE22nixos-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.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 client on /build/vm-state-client.26░ Spawning container server on /build/vm-state-server.27server # No journal files were found.28server # No journal boot entry found for the specified boot (+0).29client # No journal files were found.30client # No journal boot entry found for the specified boot (+0).31server # [361735.857822] server systemd-journald[89]: Journal started32server # [361735.857875] server systemd-journald[89]: Runtime Journal (/run/log/journal/32dfac4950a1491bbc19b5eb133789f8) is 8M, max 2.5G, 2.4G free.33server # [361735.866481] server systemd[1]: Finished Apply Kernel Variables.34server # [361735.878071] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.35server # [361735.891274] server systemd[1]: Starting Flush Journal to Persistent Storage...36server # [361735.892106] server systemd[1]: Starting Network Name Resolution...37server # [361735.892770] server systemd[1]: Starting Create Static Device Nodes in /dev...38server # [361735.902117] server systemd-journald[89]: Time spent on flushing to /var/log/journal/32dfac4950a1491bbc19b5eb133789f8 is 2.614ms for 7 entries.39server # [361735.902117] server systemd-journald[89]: System Journal (/var/log/journal/32dfac4950a1491bbc19b5eb133789f8) is 8M, max 4G, 3.9G free.40server # [361735.920435] server systemd[1]: Finished Create Static Device Nodes in /dev.41server # [361735.921017] server systemd[1]: Reached target Preparation for Local File Systems.42server # [361735.921127] server systemd[1]: Reached target Local File Systems.43server # [361735.921985] server systemd[1]: Listening on Boot Loader Control Service Socket.44server # [361735.922031] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container45server # [361735.922867] server systemd[1]: Starting Save Transient machine-id to Disk...46server # [361735.922903] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys47server # [361735.940460] server systemd[1]: Finished Flush Journal to Persistent Storage.48server # [361735.941354] server systemd[1]: Starting Create System Files and Directories...49server # [361735.957894] server systemd-tmpfiles[153]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted50server # [361735.958061] server systemd-tmpfiles[153]: fchmod() of /var/log/journal failed: Operation not permitted51server # [361735.958178] server systemd-tmpfiles[153]: fchmod() of /var/log/journal/32dfac4950a1491bbc19b5eb133789f8 failed: Operation not permitted52server # [361735.958351] server systemd-tmpfiles[153]: fchmod() of /run/log/journal failed: Operation not permitted53server # [361735.959652] server systemd[1]: Finished Create System Files and Directories.54server # [361735.960658] server systemd[1]: Starting Rebuild Journal Catalog...55server # [361735.961354] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...56server # [361735.975371] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.57server # [361735.984057] server systemd[1]: Finished Rebuild Journal Catalog.58server # [361735.985329] server systemd[1]: Starting Update is Completed...59server # [361735.995350] server systemd[1]: Finished Update is Completed.60client # [361735.863571] client systemd-journald[72]: Journal started61client # [361735.863633] client systemd-journald[72]: Runtime Journal (/run/log/journal/d80136bc31ea4153b5f8ba6e30bb8e56) is 8M, max 2.5G, 2.4G free.62client # [361735.866526] client systemd[1]: Finished Apply Kernel Variables.63client # [361735.878105] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.64client # [361735.891456] client systemd[1]: Starting Flush Journal to Persistent Storage...65client # [361735.892616] client systemd[1]: Starting Network Name Resolution...66client # [361735.893258] client systemd[1]: Starting Create Static Device Nodes in /dev...67client # [361735.902135] client systemd-journald[72]: Time spent on flushing to /var/log/journal/d80136bc31ea4153b5f8ba6e30bb8e56 is 1.631ms for 7 entries.68client # [361735.902135] client systemd-journald[72]: System Journal (/var/log/journal/d80136bc31ea4153b5f8ba6e30bb8e56) is 8M, max 4G, 3.9G free.69client # [361735.920723] client systemd[1]: Finished Create Static Device Nodes in /dev.70client # [361735.921285] client systemd[1]: Reached target Preparation for Local File Systems.71client # [361735.921386] client systemd[1]: Reached target Local File Systems.72client # [361735.922226] client systemd[1]: Listening on Boot Loader Control Service Socket.73server # [361736.023844] server systemd[1]: Finished Firewall.74client # [361735.922276] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container75server # [361736.024013] server systemd[1]: Reached target Preparation for Network.76client # [361735.923121] client systemd[1]: Starting Save Transient machine-id to Disk...77server # [361736.024242] server systemd[1]: Listening on Network Management Resolve Hook Socket.78client # [361735.923158] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys79server # [361736.025387] server systemd[1]: Starting Network Management...80client # [361735.939942] client systemd[1]: Finished Flush Journal to Persistent Storage.81server # [361736.652352] server systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted82client # [361735.941379] client systemd[1]: Starting Create System Files and Directories...83server # [361736.652451] server systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted84client # [361735.955541] client systemd-tmpfiles[136]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted85server # [361736.661721] server systemd-networkd[204]: /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.86client # [361735.955710] client systemd-tmpfiles[136]: fchmod() of /var/log/journal failed: Operation not permitted87server # [361736.661885] server systemd-networkd[204]: /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.88client # [361735.955837] client systemd-tmpfiles[136]: fchmod() of /var/log/journal/d80136bc31ea4153b5f8ba6e30bb8e56 failed: Operation not permitted89server # [361736.662042] server systemd-networkd[204]: lo: Link UP90client # [361735.956025] client systemd-tmpfiles[136]: fchmod() of /run/log/journal failed: Operation not permitted91server # [361736.662044] server systemd-networkd[204]: lo: Gained carrier92client # [361735.957219] client systemd[1]: Finished Create System Files and Directories.93server # [361736.662220] server systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.94client # [361735.958208] client systemd[1]: Starting Rebuild Journal Catalog...95server # [361736.662603] server systemd[1]: Started Network Management.96client # [361735.959166] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...97client # [361735.972900] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.98client # [361735.977249] client systemd[1]: Finished Rebuild Journal Catalog.99client # [361735.978504] client systemd[1]: Starting Update is Completed...100client # [361735.990689] client systemd[1]: Finished Update is Completed.101client # [361736.021074] client systemd[1]: Finished Firewall.102client # [361736.021231] client systemd[1]: Reached target Preparation for Network.103client # [361736.021451] client systemd[1]: Listening on Network Management Resolve Hook Socket.104client # [361736.022550] client systemd[1]: Starting Network Management...105server # [361736.662714] server systemd-networkd[204]: eth1: Link UP106client # [361736.665703] client systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted107server # [361736.663044] server systemd-networkd[204]: eth1: Gained carrier108client # [361736.665796] client systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted109server # [361736.663722] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...110client # [361736.675045] client systemd-networkd[185]: /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.111server # [361736.721710] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.112client # [361736.675209] client systemd-networkd[185]: /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.113client # [361736.675356] client systemd-networkd[185]: lo: Link UP114client # [361736.675362] client systemd-networkd[185]: lo: Gained carrier115client # [361736.675557] client systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.116client # [361736.675940] client systemd[1]: Started Network Management.117client # [361736.712251] client systemd-networkd[185]: eth1: Link UP118client # [361736.712553] client systemd-networkd[185]: eth1: Gained carrier119client # [361736.712589] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...120client # [361736.746010] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.121client # [361736.994389] client systemd-resolved[106]: Positive Trust Anchors:122client # [361736.994400] client systemd-resolved[106]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d123client # [361736.994404] client systemd-resolved[106]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16124client # [361736.994438] client systemd-resolved[106]: 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 test125client # [361737.014701] client systemd-resolved[106]: Using system hostname 'client'.126client # [361737.015975] client systemd[1]: Started Network Name Resolution.127client # [361737.016074] client systemd[1]: Reached target Network.128client # [361737.016151] client systemd[1]: Reached target System Initialization.129client # [361737.016204] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container130client # [361737.016245] client systemd[1]: Started Daily Cleanup of Temporary Directories.131client # [361737.016264] client systemd[1]: Reached target Timer Units.132client # [361737.016409] client systemd[1]: Listening on D-Bus System Message Bus Socket.133client # [361737.016541] client systemd[1]: Listening on Nix Daemon Socket.134client # [361737.016676] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.135client # [361737.016702] client systemd[1]: Reached target Socket Units.136client # [361737.016754] client systemd[1]: Reached target Basic System.137client # [361737.017838] client systemd[1]: Starting Import lastlog data into lastlog2 database...138client # [361737.018831] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...139client # [361737.020506] client systemd[1]: Starting D-Bus System Message Bus...140client # [361737.070400] client systemd[1]: Finished Import lastlog data into lastlog2 database.141client # [361737.123612] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.142client # [361737.125322] client systemd[1]: Finished Save Transient machine-id to Disk.143server # [361736.986201] server systemd-resolved[121]: Positive Trust Anchors:144server # [361736.986211] server systemd-resolved[121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d145server # [361736.986216] server systemd-resolved[121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16146server # [361736.986250] server systemd-resolved[121]: 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 test147server # [361737.006619] server systemd-resolved[121]: Using system hostname 'server'.148server # [361737.007985] server systemd[1]: Started Network Name Resolution.149server # [361737.008146] server systemd[1]: Reached target Network.150server # [361737.008248] server systemd[1]: Reached target Network is Online.151server # [361737.008334] server systemd[1]: Reached target System Initialization.152server # [361737.008429] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container153server # [361737.008487] server systemd[1]: Started Daily Cleanup of Temporary Directories.154server # [361737.008529] server systemd[1]: Reached target Timer Units.155server # [361737.008787] server systemd[1]: Listening on D-Bus System Message Bus Socket.156server # [361737.009003] server systemd[1]: Listening on Nix Daemon Socket.157server # [361737.011560] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).158server # [361737.014015] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).159server # [361737.015173] server systemd[1]: Listening on SSH Socket.160server # [361737.015434] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.161server # [361737.015498] server systemd[1]: Reached target Socket Units.162server # [361737.015603] server systemd[1]: Reached target Basic System.163server # [361737.017358] server systemd[1]: Starting Import lastlog data into lastlog2 database...164server # [361737.018795] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...165server # [361737.020380] server systemd[1]: Starting Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test]...166server # [361737.020433] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.167server # [361737.052491] server systemd[1]: Starting D-Bus System Message Bus...168server # [361737.112350] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.169server # [361737.136674] server systemd[1]: Finished Save Transient machine-id to Disk.170server # [361737.137265] server systemd[1]: lastlog2-import.service: Failed to spawn executor: No such file or directory171server # [361737.137297] server systemd[1]: lastlog2-import.service: Failed to spawn 'start-post' task: No such file or directory172server # [361737.137348] server systemd[1]: lastlog2-import.service: Failed with result 'resources'.173server # [361737.137423] server systemd[1]: Failed to start Import lastlog data into lastlog2 database.174client # [361737.289759] client systemd[1]: Started Name Service Cache Daemon (nsncd).175client # [361737.289882] client systemd[1]: Reached target Host and Network Name Lookups.176client # [361737.289989] client systemd[1]: Reached target User and Group Name Lookups.177client # [361737.290131] client nsncd[191]: Oct 13 15:34:00.247 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"178client # [361737.292291] client systemd[1]: Starting User Login Management...179client # [361737.293752] client systemd[1]: Starting Permit User Sessions...180client # [361737.305417] client systemd[1]: Finished Permit User Sessions.181client # [361737.306415] client systemd[1]: Started Console Getty.182client # [361737.306459] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0183client # [361737.306476] client systemd[1]: Reached target Login Prompts.184client # [361737.509803] client dbus-broker-launch[192]: Looking up NSS user entry for 'systemd-timesync'...185client # [361737.510977] client dbus-broker-launch[192]: NSS returned no entry for 'systemd-timesync'186client # [361737.510977] client dbus-broker-launch[192]: Invalid user-name in /nix/store/4kcbwj85pkm54mz7c7d68f5c5688mjq4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"187client # [361737.511536] client systemd[1]: Started D-Bus System Message Bus.188client # [361737.520894] client dbus-broker-launch[192]: Ready189server # [361737.310053] server nsncd[210]: Oct 13 15:34:00.267 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"190server # [361737.310236] server systemd[1]: Started Name Service Cache Daemon (nsncd).191server # [361737.310345] server systemd[1]: Reached target Host and Network Name Lookups.192server # [361737.310443] server systemd[1]: Reached target User and Group Name Lookups.193server # [361737.312335] server systemd[1]: Starting User Login Management...194server # [361737.313617] server systemd[1]: Starting Permit User Sessions...195server # [361737.325230] server systemd[1]: Finished Permit User Sessions.196server # [361737.326792] server systemd[1]: Started Console Getty.197server # [361737.326872] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0198server # [361737.326911] server systemd[1]: Reached target Login Prompts.199server # [361737.476208] server dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...200server # [361737.477730] server dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'201server # [361737.477730] server dbus-broker-launch[212]: Invalid user-name in /nix/store/wwzkgfwdc4imz50r0gv6jj38ihcrqy70-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"202server # [361737.478296] server systemd[1]: Started D-Bus System Message Bus.203server # [361737.488824] server dbus-broker-launch[212]: Ready204server # [361737.499170] server systemd[1]: Started Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test].205server # [361737.572553] server systemd-resolved[121]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.206server # [361737.692168] server systemd-networkd[204]: eth1: Gained IPv6LL207client # [361738.084510] client systemd-logind[208]: New seat seat0.208client # [361738.084696] client systemd[1]: Started User Login Management.209client # [361738.086694] client systemd[1]: Starting linger-users.service...210client # [361738.135755] client systemd[1]: linger-users.service: Deactivated successfully.211client # [361738.136090] client systemd[1]: Finished linger-users.service.212client # [361738.137004] client systemd[1]: Reached target Multi-User System.213client # [361738.137376] client systemd[1]: Startup finished in 2.745s.214client # [361738.140101] client systemd-networkd[185]: eth1: Gained IPv6LL215server # [361738.105256] server systemd-logind[226]: New seat seat0.216server # [361738.105452] server systemd[1]: Started User Login Management.217server # [361738.124672] server systemd[1]: Starting linger-users.service...218server # [361738.139785] server systemd[1]: linger-users.service: Deactivated successfully.219server # [361738.139928] server systemd[1]: Finished linger-users.service.220server # [361738.140768] server systemd[1]: Reached target Multi-User System.221server # [361738.141105] server systemd[1]: Startup finished in 2.739s.222Connection to 127.0.0.1 22 port [tcp/ssh] succeeded!223server: (finished: waiting for success: /nix/store/95cdzm5yr43vsmnzksqwby90jxkp8z3q-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 22, in 3.66 seconds)224server: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service225server: (finished: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service, in 0.02 seconds)226server: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret227server: (finished: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret, in 0.01 seconds)228(finished: run the VM test script, in 3.69 seconds)229test script finished in 3.81s230cleanup231kill NspawnMachine (pid 52)232kill NspawnMachine (pid 53)233server # [361738.510849] server systemd[1]: Created slice Slice /system/sshd.234server # [361738.511015] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.235server # [361738.512624] server systemd[1]: Started SSH per-connection Daemon (127.0.0.1:45988).236server # [361738.585451] server sshd-session[323]: Connection closed by 127.0.0.1 port 45988 [preauth]237server # [361738.587035] server systemd[1]: sshd@0-192523-127.0.0.1:22-127.0.0.1:45988.service: Deactivated successfully.238Container client terminated by signal KILL.239(finished: cleanup, in 0.33 seconds)240Container server terminated by signal KILL.