container-test-run-clan-test-p2p-ssh-iroh
checks.aarch64-linux.p2p-ssh-iroh
· build #505
· 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 client, server,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 VMs12client: systemd-nspawn running (pid 52)13server: systemd-nspawn running (pid 54)14client: Waiting for journal at /build/vm-state-client/var/log/journal...15server: Waiting for journal at /build/vm-state-server/var/log/journal...16(finished: start all VMs, in 0.00 seconds)17server: waiting for success: /nix/store/3iqndhxbb5292makx3hfgadvsfgwric3-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 2218nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE19nixos-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.20nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.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 client on /build/vm-state-client.25░ Spawning container server on /build/vm-state-server.26client # [6785521.034853] client systemd-journald[69]: Journal started27client # [6785521.034914] client systemd-journald[69]: Runtime Journal (/run/log/journal/651efac39f2041a188a399afa409cc76) is 8M, max 2.5G, 2.4G free.28client # [6785521.039020] client systemd[1]: Starting Flush Journal to Persistent Storage...29client # [6785521.040029] client systemd[1]: Starting Network Name Resolution...30client # [6785521.040826] client systemd[1]: Starting Create Static Device Nodes in /dev...31client # [6785521.048485] client systemd-journald[69]: Time spent on flushing to /var/log/journal/651efac39f2041a188a399afa409cc76 is 1.491ms for 5 entries.32client # [6785521.048485] client systemd-journald[69]: System Journal (/var/log/journal/651efac39f2041a188a399afa409cc76) is 8M, max 4G, 3.9G free.33client # [6785521.053251] client systemd[1]: Finished Create Static Device Nodes in /dev.34client # [6785521.053500] client systemd[1]: Reached target Preparation for Local File Systems.35client # [6785521.053580] client systemd[1]: Reached target Local File Systems.36client # [6785521.054309] client systemd[1]: Listening on Boot Loader Control Service Socket.37client # [6785521.054348] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container38client # [6785521.055261] client systemd[1]: Starting Save Transient machine-id to Disk...39client # [6785521.055298] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys40client # [6785521.059207] client systemd[1]: Finished Flush Journal to Persistent Storage.41client # [6785521.060177] client systemd[1]: Starting Create System Files and Directories...42client # [6785521.076363] client systemd-tmpfiles[109]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted43client # [6785521.076769] client systemd-tmpfiles[109]: fchmod() of /var/log/journal failed: Operation not permitted44client # [6785521.076917] client systemd-tmpfiles[109]: fchmod() of /var/log/journal/651efac39f2041a188a399afa409cc76 failed: Operation not permitted45client # [6785521.077127] client systemd-tmpfiles[109]: fchmod() of /run/log/journal failed: Operation not permitted46client # [6785521.079648] client systemd[1]: Finished Create System Files and Directories.47client # [6785521.080979] client systemd[1]: Starting Rebuild Journal Catalog...48client # [6785521.081753] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...49client # [6785521.082285] client systemd[1]: Finished Save Transient machine-id to Disk.50client # [6785521.094111] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.51client # [6785521.101651] client systemd[1]: Finished Rebuild Journal Catalog.52client # [6785521.102719] client systemd[1]: Starting Update is Completed...53client # [6785521.113970] client systemd[1]: Finished Update is Completed.54server # [6785521.054120] server systemd-journald[87]: Journal started55server # [6785521.054171] server systemd-journald[87]: Runtime Journal (/run/log/journal/46803e6b1b6a4b6eb4c36bad169303c5) is 8M, max 2.5G, 2.4G free.56server # [6785521.062286] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.57server # [6785521.071070] server systemd[1]: Starting Flush Journal to Persistent Storage...58server # [6785521.072106] server systemd[1]: Starting Network Name Resolution...59server # [6785521.072828] server systemd[1]: Starting Create Static Device Nodes in /dev...60server # [6785521.079844] server systemd-journald[87]: Time spent on flushing to /var/log/journal/46803e6b1b6a4b6eb4c36bad169303c5 is 1.154ms for 6 entries.61server # [6785521.079844] server systemd-journald[87]: System Journal (/var/log/journal/46803e6b1b6a4b6eb4c36bad169303c5) is 8M, max 4G, 3.9G free.62server # [6785521.085664] server systemd[1]: Finished Create Static Device Nodes in /dev.63server # [6785521.086306] server systemd[1]: Reached target Preparation for Local File Systems.64server # [6785521.086418] server systemd[1]: Reached target Local File Systems.65server # [6785521.087216] server systemd[1]: Listening on Boot Loader Control Service Socket.66server # [6785521.087258] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container67server # [6785521.088249] server systemd[1]: Starting Save Transient machine-id to Disk...68server # [6785521.088282] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys69server # [6785521.092339] server systemd[1]: Finished Flush Journal to Persistent Storage.70server # [6785521.093232] server systemd[1]: Starting Create System Files and Directories...71server # [6785521.108082] server systemd[1]: Finished Save Transient machine-id to Disk.72server # [6785521.109469] server systemd-tmpfiles[131]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted73server # [6785521.109659] server systemd-tmpfiles[131]: fchmod() of /var/log/journal failed: Operation not permitted74server # [6785521.109788] server systemd-tmpfiles[131]: fchmod() of /var/log/journal/46803e6b1b6a4b6eb4c36bad169303c5 failed: Operation not permitted75server # [6785521.109991] server systemd-tmpfiles[131]: fchmod() of /run/log/journal failed: Operation not permitted76server # [6785521.111336] server systemd[1]: Finished Create System Files and Directories.77server # [6785521.112353] server systemd[1]: Starting Rebuild Journal Catalog...78server # [6785521.113019] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...79server # [6785521.126762] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.80server # [6785521.134670] server systemd[1]: Finished Rebuild Journal Catalog.81server # [6785521.135656] server systemd[1]: Starting Update is Completed...82server # [6785521.145262] server systemd[1]: Finished Update is Completed.83server # [6785521.234811] server systemd[1]: Finished Firewall.84server # [6785521.235421] server systemd[1]: Reached target Preparation for Network.85server # [6785521.235748] server systemd[1]: Listening on Network Management Resolve Hook Socket.86server # [6785521.236876] server systemd[1]: Starting Network Management...87client # [6785521.230914] client systemd[1]: Finished Firewall.88client # [6785521.231628] client systemd[1]: Reached target Preparation for Network.89client # [6785521.231909] client systemd[1]: Listening on Network Management Resolve Hook Socket.90client # [6785521.232991] client systemd[1]: Starting Network Management...91client # [6785521.667292] client systemd-networkd[183]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted92client # [6785521.667382] client systemd-networkd[183]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted93client # [6785521.676873] client systemd-networkd[183]: /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.94client # [6785521.677043] client systemd-networkd[183]: /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.95client # [6785521.677211] client systemd-networkd[183]: lo: Link UP96client # [6785521.677219] client systemd-networkd[183]: lo: Gained carrier97client # [6785521.677430] client systemd-networkd[183]: eth1: Configuring with /etc/systemd/network/40-eth1.network.98client # [6785521.677838] client systemd[1]: Started Network Management.99client # [6785521.696459] client systemd-networkd[183]: eth1: Link UP100client # [6785521.696816] client systemd-networkd[183]: eth1: Gained carrier101client # [6785521.697014] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...102client # [6785521.734190] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.103client # [6785521.797941] client systemd-resolved[91]: Positive Trust Anchors:104client # [6785521.797953] client systemd-resolved[91]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d105client # [6785521.797957] client systemd-resolved[91]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16106client # [6785521.797990] client systemd-resolved[91]: 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 test107client # [6785521.820326] client systemd-resolved[91]: Using system hostname 'client'.108client # [6785521.821758] client systemd[1]: Started Network Name Resolution.109client # [6785521.821894] client systemd[1]: Reached target Network.110client # [6785521.822011] client systemd[1]: Reached target System Initialization.111client # [6785521.822116] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container112client # [6785521.822176] client systemd[1]: Started Daily Cleanup of Temporary Directories.113client # [6785521.822214] client systemd[1]: Reached target Timer Units.114client # [6785521.822438] client systemd[1]: Listening on D-Bus System Message Bus Socket.115client # [6785521.822988] client systemd[1]: Listening on Nix Daemon Socket.116client # [6785521.823220] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.117client # [6785521.823269] client systemd[1]: Reached target Socket Units.118client # [6785521.823357] client systemd[1]: Reached target Basic System.119client # [6785521.825147] client systemd[1]: Starting Import lastlog data into lastlog2 database...120client # [6785521.826630] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...121client # [6785521.828806] client systemd[1]: Starting D-Bus System Message Bus...122client # [6785521.879444] client systemd[1]: Finished Import lastlog data into lastlog2 database.123server # [6785521.673412] server systemd-networkd[203]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted124server # [6785521.673500] server systemd-networkd[203]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted125server # [6785521.680037] server systemd-networkd[203]: /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.126server # [6785521.680205] server systemd-networkd[203]: /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.127server # [6785521.680368] server systemd-networkd[203]: lo: Link UP128server # [6785521.680372] server systemd-networkd[203]: lo: Gained carrier129server # [6785521.680551] server systemd-networkd[203]: eth1: Configuring with /etc/systemd/network/40-eth1.network.130server # [6785521.680917] server systemd[1]: Started Network Management.131server # [6785521.696526] server systemd-networkd[203]: eth1: Link UP132server # [6785521.696884] server systemd-networkd[203]: eth1: Gained carrier133server # [6785521.697136] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...134server # [6785521.733581] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.135server # [6785521.834798] server systemd-resolved[113]: Positive Trust Anchors:136server # [6785521.834812] server systemd-resolved[113]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d137server # [6785521.834815] server systemd-resolved[113]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16138server # [6785521.834851] server systemd-resolved[113]: 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 test139server # [6785521.857391] server systemd-resolved[113]: Using system hostname 'server'.140server # [6785521.858886] server systemd[1]: Started Network Name Resolution.141server # [6785521.859032] server systemd[1]: Reached target Network.142server # [6785521.859134] server systemd[1]: Reached target Network is Online.143server # [6785521.859223] server systemd[1]: Reached target System Initialization.144server # [6785521.859320] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container145server # [6785521.859377] server systemd[1]: Started Daily Cleanup of Temporary Directories.146server # [6785521.859415] server systemd[1]: Reached target Timer Units.147server # [6785521.859630] server systemd[1]: Listening on D-Bus System Message Bus Socket.148server # [6785521.859842] server systemd[1]: Listening on Nix Daemon Socket.149server # [6785521.862368] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).150server # [6785521.864710] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).151server # [6785521.865930] server systemd[1]: Listening on SSH Socket.152server # [6785521.866153] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.153server # [6785521.866206] server systemd[1]: Reached target Socket Units.154server # [6785521.866288] server systemd[1]: Reached target Basic System.155server # [6785521.867922] server systemd[1]: Starting Import lastlog data into lastlog2 database...156server # [6785521.869295] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...157server # [6785521.870792] server systemd[1]: Starting Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test]...158server # [6785521.870846] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.159server # [6785521.874066] server systemd[1]: Starting D-Bus System Message Bus...160server # [6785521.888655] server systemd[1]: Finished Import lastlog data into lastlog2 database.161client # [6785522.017100] client nsncd[189]: Aug 26 12:15:48.070 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"162server # [6785522.026123] server nsncd[209]: Aug 26 12:15:48.079 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"163client # [6785522.019309] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.164server # [6785522.026237] server systemd[1]: Started Name Service Cache Daemon (nsncd).165client # [6785522.020065] client systemd[1]: Started Name Service Cache Daemon (nsncd).166server # [6785522.026295] server systemd[1]: Reached target Host and Network Name Lookups.167client # [6785522.020112] client systemd[1]: Reached target Host and Network Name Lookups.168server # [6785522.026395] server systemd[1]: Reached target User and Group Name Lookups.169client # [6785522.020167] client systemd[1]: Reached target User and Group Name Lookups.170server # [6785522.027496] server systemd[1]: Starting User Login Management...171client # [6785522.021399] client systemd[1]: Starting User Login Management...172server # [6785522.028247] server systemd[1]: Starting Permit User Sessions...173client # [6785522.022193] client systemd[1]: Starting Permit User Sessions...174server # [6785522.048583] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.175client # [6785522.031955] client systemd[1]: Finished Permit User Sessions.176server # [6785522.049564] server systemd[1]: Finished Permit User Sessions.177client # [6785522.033017] client systemd[1]: Started Console Getty.178server # [6785522.050917] server systemd[1]: Started Console Getty.179client # [6785522.033056] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0180server # [6785522.050956] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0181client # [6785522.033072] client systemd[1]: Reached target Login Prompts.182server # [6785522.050974] server systemd[1]: Reached target Login Prompts.183client # [6785522.129580] client dbus-broker-launch[190]: Looking up NSS user entry for 'systemd-timesync'...184server # [6785522.133144] server dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...185client # [6785522.130300] client dbus-broker-launch[190]: NSS returned no entry for 'systemd-timesync'186server # [6785522.133989] server dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'187client # [6785522.130300] client dbus-broker-launch[190]: Invalid user-name in /nix/store/5a0c1jkfwi21h99izphv1z1r8qyn2m1d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"188server # [6785522.134116] server dbus-broker-launch[212]: Invalid user-name in /nix/store/0lmfcd8xr32an8nh7b7syz3pyrl24fmp-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"189client # [6785522.130757] client systemd[1]: Started D-Bus System Message Bus.190server # [6785522.134561] server systemd[1]: Started D-Bus System Message Bus.191client # [6785522.137862] client dbus-broker-launch[190]: Ready192server # [6785522.142045] server dbus-broker-launch[212]: Ready193server # [6785522.142667] server systemd[1]: Started Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test].194server # [6785522.200055] server systemd-resolved[113]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.195Connection to 127.0.0.1 22 port [tcp/ssh] succeeded!196server: (finished: waiting for success: /nix/store/3iqndhxbb5292makx3hfgadvsfgwric3-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 22, in 2.64 seconds)197server: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service198server # [6785522.530309] server systemd-logind[226]: New seat seat0.199server # [6785522.530543] server systemd[1]: Started User Login Management.200server # [6785522.531642] server systemd[1]: Starting linger-users.service...201server # [6785522.545891] server systemd[1]: linger-users.service: Deactivated successfully.202server # [6785522.545981] server systemd[1]: Finished linger-users.service.203server # [6785522.546365] server systemd[1]: Reached target Multi-User System.204server # [6785522.564167] server systemd[1]: Startup finished in 1.902s.205server # [6785522.856464] server systemd[1]: Created slice Slice /system/sshd.206server # [6785522.856543] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.207server: (finished: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service, in 0.01 seconds)208server: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret209server: (finished: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret, in 0.00 seconds)210(finished: run the VM test script, in 2.66 seconds)211client # [6785522.525435] client systemd-logind[205]: New seat seat0.212client # [6785522.525653] client systemd[1]: Started User Login Management.213client # [6785522.526954] client systemd[1]: Starting linger-users.service...214client # [6785522.541294] client systemd[1]: linger-users.service: Deactivated successfully.215client # [6785522.541448] client systemd[1]: Finished linger-users.service.216client # [6785522.541926] client systemd[1]: Reached target Multi-User System.217client # [6785522.542128] client systemd[1]: Startup finished in 1.895s.218test script finished in 2.74s219cleanup220kill NspawnMachine (pid 52)221kill NspawnMachine (pid 54)222Container client terminated by signal KILL.223server # [6785522.857609] server systemd[1]: Started SSH per-connection Daemon (127.0.0.1:47684).224server # [6785522.880159] server systemd-networkd[203]: eth1: Gained IPv6LL225server # [6785522.884090] server sshd-session[324]: Connection closed by 127.0.0.1 port 47684 [preauth]226server # [6785522.885183] server systemd[1]: sshd@0-291181-127.0.0.1:22-127.0.0.1:47684.service: Deactivated successfully.227server # [6785523.068549] server systemd-networkd[203]: eth1: Link DOWN228server # [6785523.068563] server systemd-networkd[203]: eth1: Lost carrier229server # [6785523.128422] server systemd-networkd[203]: eth1: Lost IPv6LL address fe80::38be:b3ff:fee1:4538.230Container server terminated by signal KILL.231(finished: cleanup, in 0.38 seconds)