container-test-run-clan-test-p2p-ssh-iroh
checks.aarch64-linux.p2p-ssh-iroh
· build #1080
· raw
1build01: building zjcmn0fmw5zz34kpfinv1ac361m986x5-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)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_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/1553icvwp9ryqw3hng22rrah30jw9pn0-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 2219nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE20nixos-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.21nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE22nixos-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.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 server on /build/vm-state-server.26░ Spawning container client on /build/vm-state-client.27client # No journal files were found.28client # No journal boot entry found for the specified boot (+0).29server # No journal files were found.30server # No journal boot entry found for the specified boot (+0).31client # [524386.160675] client systemd-journald[73]: Journal started32client # [524386.160742] client systemd-journald[73]: Runtime Journal (/run/log/journal/dbf6217870394f21a670872503b09370) is 8M, max 2.5G, 2.4G free.33client # [524386.216160] client systemd[1]: Finished Apply Kernel Variables.34client # [524386.262185] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.35client # [524386.292985] client systemd[1]: Starting Flush Journal to Persistent Storage...36client # [524386.294089] client systemd[1]: Starting Network Name Resolution...37client # [524386.294955] client systemd[1]: Starting Create Static Device Nodes in /dev...38client # [524386.336514] client systemd[1]: Finished Firewall.39client # [524386.336825] client systemd[1]: Reached target Preparation for Network.40client # [524386.337164] client systemd[1]: Listening on Network Management Resolve Hook Socket.41client # [524386.343324] client systemd-journald[73]: Time spent on flushing to /var/log/journal/dbf6217870394f21a670872503b09370 is 1.711ms for 10 entries.42server # [524386.194202] server systemd-journald[91]: Journal started43client # [524386.343324] client systemd-journald[73]: System Journal (/var/log/journal/dbf6217870394f21a670872503b09370) is 8M, max 4G, 3.9G free.44server # [524386.194257] server systemd-journald[91]: Runtime Journal (/run/log/journal/f20d058fb83540e3b397219d3541cc82) is 8M, max 2.5G, 2.4G free.45client # [524386.407723] client systemd[1]: Finished Flush Journal to Persistent Storage.46server # [524386.262289] server systemd[1]: Finished Apply Kernel Variables.47client # [524386.410884] client systemd[1]: Finished Create Static Device Nodes in /dev.48server # [524386.292214] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.49client # [524386.412113] client systemd[1]: Reached target Preparation for Local File Systems.50server # [524386.336667] server systemd[1]: Starting Flush Journal to Persistent Storage...51client # [524386.412225] client systemd[1]: Reached target Local File Systems.52server # [524386.337886] server systemd[1]: Starting Network Name Resolution...53client # [524386.413021] client systemd[1]: Listening on Boot Loader Control Service Socket.54server # [524386.338794] server systemd[1]: Starting Create Static Device Nodes in /dev...55client # [524386.413079] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container56server # [524386.348066] server systemd-journald[91]: Time spent on flushing to /var/log/journal/f20d058fb83540e3b397219d3541cc82 is 1.660ms for 7 entries.57client # [524386.414356] client systemd[1]: Starting Save Transient machine-id to Disk...58server # [524386.348066] server systemd-journald[91]: System Journal (/var/log/journal/f20d058fb83540e3b397219d3541cc82) is 8M, max 4G, 3.9G free.59client # [524386.415167] client systemd[1]: Starting Create System Files and Directories...60server # [524386.400785] server systemd[1]: Finished Firewall.61client # [524386.415205] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys62server # [524386.401003] server systemd[1]: Finished Flush Journal to Persistent Storage.63client # [524386.416212] client systemd[1]: Starting Network Management...64server # [524386.401847] server systemd[1]: Finished Create Static Device Nodes in /dev.65client # [524386.431397] client systemd-tmpfiles[178]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted66server # [524386.403845] server systemd[1]: Reached target Preparation for Local File Systems.67client # [524386.432037] client systemd-tmpfiles[178]: fchmod() of /var/log/journal failed: Operation not permitted68server # [524386.403956] server systemd[1]: Reached target Local File Systems.69client # [524386.432159] client systemd-tmpfiles[178]: fchmod() of /var/log/journal/dbf6217870394f21a670872503b09370 failed: Operation not permitted70server # [524386.404020] server systemd[1]: Reached target Preparation for Network.71client # [524386.432325] client systemd-tmpfiles[178]: fchmod() of /run/log/journal failed: Operation not permitted72server # [524386.404730] server systemd[1]: Listening on Boot Loader Control Service Socket.73client # [524386.434801] client systemd[1]: Finished Create System Files and Directories.74server # [524386.404909] server systemd[1]: Listening on Network Management Resolve Hook Socket.75client # [524386.435883] client systemd[1]: Starting Rebuild Journal Catalog...76server # [524386.404955] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container77client # [524386.436624] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...78server # [524386.405889] server systemd[1]: Starting Save Transient machine-id to Disk...79client # [524386.448619] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.80server # [524386.406686] server systemd[1]: Starting Create System Files and Directories...81client # [524386.455641] client systemd[1]: Finished Rebuild Journal Catalog.82server # [524386.406723] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys83client # [524386.456817] client systemd[1]: Starting Update is Completed...84server # [524386.407707] server systemd[1]: Starting Network Management...85client # [524386.467663] client systemd[1]: Finished Update is Completed.86server # [524386.422534] server systemd-tmpfiles[198]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted87client # [524386.481876] client systemd[1]: Finished Save Transient machine-id to Disk.88server # [524386.422704] server systemd-tmpfiles[198]: fchmod() of /var/log/journal failed: Operation not permitted89client # [524387.161911] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.90server # [524386.422818] server systemd-tmpfiles[198]: fchmod() of /var/log/journal/f20d058fb83540e3b397219d3541cc82 failed: Operation not permitted91server # [524386.422977] server systemd-tmpfiles[198]: fchmod() of /run/log/journal failed: Operation not permitted92server # [524386.426805] server systemd[1]: Finished Create System Files and Directories.93server # [524386.427987] server systemd[1]: Starting Rebuild Journal Catalog...94server # [524386.428824] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...95server # [524386.440869] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.96server # [524386.448018] server systemd[1]: Finished Rebuild Journal Catalog.97server # [524386.449408] server systemd[1]: Starting Update is Completed...98server # [524386.460923] server systemd[1]: Finished Update is Completed.99server # [524386.484529] server systemd[1]: Finished Save Transient machine-id to Disk.100server # [524387.207779] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.101client # [524388.512149] client systemd-networkd[179]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted102client # [524388.512253] client systemd-networkd[179]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted103client # [524388.525287] 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.104client # [524388.525457] 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.105client # [524388.525724] client systemd-networkd[179]: lo: Link UP106client # [524388.525728] client systemd-networkd[179]: lo: Gained carrier107client # [524388.526027] client systemd-networkd[179]: eth1: Configuring with /etc/systemd/network/40-eth1.network.108client # [524388.526586] client systemd-networkd[179]: eth1: Link UP109client # [524388.526781] client systemd[1]: Started Network Management.110client # [524388.526902] client systemd-networkd[179]: eth1: Gained carrier111client # [524388.528874] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...112client # [524388.590131] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.113server # [524388.522284] server systemd-networkd[199]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted114server # [524388.522386] server systemd-networkd[199]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted115server # [524388.536569] server systemd-networkd[199]: /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.116server # [524388.536740] server systemd-networkd[199]: /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.117server # [524388.536913] server systemd-networkd[199]: lo: Link UP118server # [524388.536917] server systemd-networkd[199]: lo: Gained carrier119server # [524388.537128] server systemd-networkd[199]: eth1: Configuring with /etc/systemd/network/40-eth1.network.120server # [524388.537638] server systemd[1]: Started Network Management.121server # [524388.580424] server systemd-networkd[199]: eth1: Link UP122server # [524388.580535] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...123server # [524388.580889] server systemd-networkd[199]: eth1: Gained carrier124server # [524388.618370] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.125client # [524389.431914] client systemd-resolved[160]: Positive Trust Anchors:126client # [524389.431929] client systemd-resolved[160]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d127client # [524389.431932] client systemd-resolved[160]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16128client # [524389.431967] client systemd-resolved[160]: 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 test129client # [524389.432807] client systemd-resolved[160]: Using system hostname 'client'.130client # [524389.434393] client systemd[1]: Started Network Name Resolution.131client # [524389.434481] client systemd[1]: Reached target Network.132client # [524389.434558] client systemd[1]: Reached target System Initialization.133client # [524389.434612] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container134client # [524389.434642] client systemd[1]: Started Daily Cleanup of Temporary Directories.135client # [524389.434660] client systemd[1]: Reached target Timer Units.136client # [524389.434789] client systemd[1]: Listening on D-Bus System Message Bus Socket.137client # [524389.434917] client systemd[1]: Listening on Nix Daemon Socket.138client # [524389.435025] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.139client # [524389.435045] client systemd[1]: Reached target Socket Units.140client # [524389.435089] client systemd[1]: Reached target Basic System.141client # [524389.480814] client systemd[1]: Starting Import lastlog data into lastlog2 database...142client # [524389.482064] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...143client # [524389.483811] client systemd[1]: Starting D-Bus System Message Bus...144client # [524389.501349] client systemd[1]: Finished Import lastlog data into lastlog2 database.145client # [524389.752107] client systemd[1]: Started Name Service Cache Daemon (nsncd).146client # [524389.752201] client systemd[1]: Reached target Host and Network Name Lookups.147client # [524389.752278] client systemd[1]: Reached target User and Group Name Lookups.148server # [524389.601584] server systemd-resolved[178]: Positive Trust Anchors:149server # [524389.601602] server systemd-resolved[178]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d150server # [524389.601605] server systemd-resolved[178]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16151server # [524389.601640] server systemd-resolved[178]: 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 test152server # [524389.602628] server systemd-resolved[178]: Using system hostname 'server'.153server # [524389.604371] server systemd[1]: Started Network Name Resolution.154server # [524389.604477] server systemd[1]: Reached target Network.155server # [524389.604542] server systemd[1]: Reached target Network is Online.156server # [524389.604582] server systemd[1]: Reached target System Initialization.157server # [524389.604639] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container158server # [524389.604665] server systemd[1]: Started Daily Cleanup of Temporary Directories.159server # [524389.604682] server systemd[1]: Reached target Timer Units.160server # [524389.604811] server systemd[1]: Listening on D-Bus System Message Bus Socket.161server # [524389.604936] server systemd[1]: Listening on Nix Daemon Socket.162server # [524389.605794] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).163server # [524389.606427] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).164server # [524389.606817] server systemd[1]: Listening on SSH Socket.165server # [524389.606917] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.166server # [524389.606940] server systemd[1]: Reached target Socket Units.167server # [524389.606981] server systemd[1]: Reached target Basic System.168server # [524389.608366] server systemd[1]: Starting Import lastlog data into lastlog2 database...169server # [524389.609252] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...170server # [524389.610153] server systemd[1]: Starting Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test]...171server # [524389.610188] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.172server # [524389.612458] server systemd[1]: Starting D-Bus System Message Bus...173server # [524389.724223] server systemd[1]: Finished Import lastlog data into lastlog2 database.174client # [524389.753858] client systemd[1]: Starting User Login Management...175client # [524389.754216] client nsncd[193]: Oct 22 11:03:14.726 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"176client # [524389.754932] client systemd[1]: Starting Permit User Sessions...177client # [524389.767597] client systemd[1]: Finished Permit User Sessions.178client # [524389.769145] client systemd[1]: Started Console Getty.179client # [524389.769198] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0180client # [524389.769220] client systemd[1]: Reached target Login Prompts.181client # [524389.884134] client systemd-networkd[179]: eth1: Gained IPv6LL182server # [524389.988716] server nsncd[213]: Oct 22 11:03:14.960 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"183server # [524389.988871] server systemd[1]: Started Name Service Cache Daemon (nsncd).184server # [524389.989026] server systemd[1]: Reached target Host and Network Name Lookups.185server # [524389.989176] server systemd[1]: Reached target User and Group Name Lookups.186server # [524389.991185] server systemd[1]: Starting User Login Management...187server # [524389.992181] server systemd[1]: Starting Permit User Sessions...188server # [524390.049265] server systemd[1]: Finished Permit User Sessions.189server # [524390.051591] server systemd[1]: Started Console Getty.190server # [524390.051728] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0191server # [524390.051844] server systemd[1]: Reached target Login Prompts.192server # [524390.082423] server systemd-networkd[199]: eth1: Gained IPv6LL193client # [524390.164959] client dbus-broker-launch[194]: Looking up NSS user entry for 'systemd-timesync'...194client # [524390.165919] client dbus-broker-launch[194]: NSS returned no entry for 'systemd-timesync'195client # [524390.165919] 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"196client # [524390.166520] client systemd[1]: Started D-Bus System Message Bus.197client # [524390.173794] client dbus-broker-launch[194]: Ready198server # [524390.263606] server dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...199server # [524390.264858] server dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'200server # [524390.264858] server dbus-broker-launch[215]: Invalid user-name in /nix/store/xrz9k5773fcmw896m1svdqzz8ba29waf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"201server # [524390.266423] server systemd[1]: Started D-Bus System Message Bus.202server # [524390.273200] server dbus-broker-launch[215]: Ready203server # [524390.283557] server systemd[1]: Started Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test].204server # [524390.339864] server systemd-resolved[178]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.205Connection to 127.0.0.1 22 port [tcp/ssh] succeeded!206server: (finished: waiting for success: /nix/store/1553icvwp9ryqw3hng22rrah30jw9pn0-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 22, in 6.15 seconds)207server: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service208server: (finished: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service, in 0.02 seconds)209server: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret210server: (finished: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret, in 0.01 seconds)211(finished: run the VM test script, in 6.18 seconds)212client # [524391.126023] client systemd-logind[204]: New seat seat0.213client # [524391.126583] client systemd[1]: Started User Login Management.214client # [524391.132803] client systemd[1]: Starting linger-users.service...215client # [524391.214921] client systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available216client # [524391.214969] client systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available217client # [524391.214999] client systemd[1]: linger-users.service: Deactivated successfully.218client # [524391.215592] client systemd[1]: Finished linger-users.service.219client # [524391.216081] client systemd[1]: Reached target Multi-User System.220client # [524391.216261] client systemd[1]: Startup finished in 5.401s.221test script finished in 6.24s222cleanup223kill NspawnMachine (pid 52)224kill NspawnMachine (pid 53)225server # [524391.410061] server systemd-logind[230]: New seat seat0.226server # [524391.412272] server systemd[1]: Started User Login Management.227server # [524391.429345] server systemd[1]: Starting linger-users.service...228server # [524391.450159] server systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available229server # [524391.450195] server systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available230server # [524391.450220] server systemd[1]: linger-users.service: Deactivated successfully.231server # [524391.450426] server systemd[1]: Finished linger-users.service.232server # [524391.450904] server systemd[1]: Reached target Multi-User System.233server # [524391.452017] server systemd[1]: Startup finished in 5.604s.234server # [524391.462008] server systemd[1]: Created slice Slice /system/sshd.235server # [524391.462089] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.236server # [524391.466902] server systemd[1]: Started SSH per-connection Daemon (127.0.0.1:54678).237server # [524391.488824] server sshd-session[327]: Connection closed by 127.0.0.1 port 54678 [preauth]238server # [524391.542522] server systemd[1]: sshd@0-274446-127.0.0.1:22-127.0.0.1:54678.service: Deactivated successfully.239Container client terminated by signal KILL.240Container server terminated by signal KILL.241(finished: cleanup, in 0.38 seconds)