container-test-run-clan-test-p2p-ssh-iroh
checks.aarch64-linux.p2p-ssh-iroh
· build #1043
· raw
1build01: building n90rqqs6anqabpbcijc9bnkaa317x0vr-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 # [312594.870743] client systemd-journald[73]: Journal started28client # [312594.870799] client systemd-journald[73]: Runtime Journal (/run/log/journal/7380d401bcaa49e0bbf0a844b206cf07) is 8M, max 2.5G, 2.4G free.29client # [312594.888846] client systemd[1]: Finished Apply Kernel Variables.30client # [312594.905494] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.31client # [312594.922852] client systemd[1]: Starting Flush Journal to Persistent Storage...32client # [312594.924077] client systemd[1]: Starting Network Name Resolution...33client # [312594.925052] client systemd[1]: Starting Create Static Device Nodes in /dev...34client # [312594.935113] client systemd-journald[73]: Time spent on flushing to /var/log/journal/7380d401bcaa49e0bbf0a844b206cf07 is 1.253ms for 7 entries.35client # [312594.935113] client systemd-journald[73]: System Journal (/var/log/journal/7380d401bcaa49e0bbf0a844b206cf07) is 8M, max 4G, 3.9G free.36client # [312594.947270] client systemd[1]: Finished Create Static Device Nodes in /dev.37client # [312594.948195] client systemd[1]: Reached target Preparation for Local File Systems.38client # [312594.948319] client systemd[1]: Reached target Local File Systems.39client # [312594.949159] client systemd[1]: Listening on Boot Loader Control Service Socket.40client # [312594.949211] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container41client # [312594.950192] client systemd[1]: Starting Save Transient machine-id to Disk...42client # [312594.950243] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys43client # [312594.952339] client systemd[1]: Finished Flush Journal to Persistent Storage.44client # [312594.953449] client systemd[1]: Starting Create System Files and Directories...45client # [312594.970203] client systemd-tmpfiles[133]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted46client # [312594.970375] client systemd-tmpfiles[133]: fchmod() of /var/log/journal failed: Operation not permitted47client # [312594.970493] client systemd-tmpfiles[133]: fchmod() of /var/log/journal/7380d401bcaa49e0bbf0a844b206cf07 failed: Operation not permitted48client # [312594.970660] client systemd-tmpfiles[133]: fchmod() of /run/log/journal failed: Operation not permitted49client # [312594.972410] client systemd[1]: Finished Create System Files and Directories.50client # [312594.973765] client systemd[1]: Starting Rebuild Journal Catalog...51client # [312594.974672] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...52client # [312594.987274] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.53client # [312594.994133] client systemd[1]: Finished Rebuild Journal Catalog.54client # [312594.995836] client systemd[1]: Starting Update is Completed...55client # [312595.007360] client systemd[1]: Finished Update is Completed.56client # [312595.043769] client systemd[1]: Finished Firewall.57client # [312595.043913] client systemd[1]: Reached target Preparation for Network.58client # [312595.044445] client systemd[1]: Listening on Network Management Resolve Hook Socket.59client # [312595.045659] client systemd[1]: Starting Network Management...60server # [312594.872740] server systemd-journald[91]: Journal started61server # [312594.872797] server systemd-journald[91]: Runtime Journal (/run/log/journal/bf00c8c59c4849d7a6fedc9a0b443fd4) is 8M, max 2.5G, 2.4G free.62server # [312594.891424] server systemd[1]: Finished Apply Kernel Variables.63server # [312594.910636] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.64server # [312594.927341] server systemd[1]: Starting Flush Journal to Persistent Storage...65server # [312594.928370] server systemd[1]: Starting Network Name Resolution...66server # [312594.929271] server systemd[1]: Starting Create Static Device Nodes in /dev...67server # [312594.937883] server systemd-journald[91]: Time spent on flushing to /var/log/journal/bf00c8c59c4849d7a6fedc9a0b443fd4 is 1.389ms for 7 entries.68server # [312594.937883] server systemd-journald[91]: System Journal (/var/log/journal/bf00c8c59c4849d7a6fedc9a0b443fd4) is 8M, max 4G, 3.9G free.69server # [312594.950584] server systemd[1]: Finished Create Static Device Nodes in /dev.70server # [312594.951294] server systemd[1]: Reached target Preparation for Local File Systems.71server # [312594.951433] server systemd[1]: Reached target Local File Systems.72server # [312594.952307] server systemd[1]: Listening on Boot Loader Control Service Socket.73server # [312594.952377] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container74server # [312594.953438] server systemd[1]: Starting Save Transient machine-id to Disk...75server # [312594.953481] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys76server # [312594.954045] server systemd[1]: Finished Flush Journal to Persistent Storage.77server # [312594.955500] server systemd[1]: Starting Create System Files and Directories...78server # [312594.973875] server systemd-tmpfiles[152]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted79server # [312594.974036] server systemd-tmpfiles[152]: fchmod() of /var/log/journal failed: Operation not permitted80server # [312594.974144] server systemd-tmpfiles[152]: fchmod() of /var/log/journal/bf00c8c59c4849d7a6fedc9a0b443fd4 failed: Operation not permitted81server # [312594.974300] server systemd-tmpfiles[152]: fchmod() of /run/log/journal failed: Operation not permitted82server # [312594.975880] server systemd[1]: Finished Create System Files and Directories.83server # [312594.977131] server systemd[1]: Starting Rebuild Journal Catalog...84server # [312594.977985] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...85server # [312594.990863] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.86server # [312594.997941] server systemd[1]: Finished Rebuild Journal Catalog.87server # [312595.000344] server systemd[1]: Starting Update is Completed...88server # [312595.012193] server systemd[1]: Finished Update is Completed.89server # [312595.048749] server systemd[1]: Finished Firewall.90server # [312595.048941] server systemd[1]: Reached target Preparation for Network.91server # [312595.049191] server systemd[1]: Listening on Network Management Resolve Hook Socket.92server # [312595.050388] server systemd[1]: Starting Network Management...93client # [312595.688258] client systemd[1]: Finished Save Transient machine-id to Disk.94client # [312595.889705] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.95server # [312595.689695] server systemd[1]: Finished Save Transient machine-id to Disk.96server # [312595.877129] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.97server # [312596.020917] server systemd-networkd[206]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted98server # [312596.021014] server systemd-networkd[206]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted99server # [312596.030470] server systemd-networkd[206]: /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.100server # [312596.030636] server systemd-networkd[206]: /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.101server # [312596.030811] server systemd-networkd[206]: lo: Link UP102server # [312596.030817] server systemd-networkd[206]: lo: Gained carrier103server # [312596.031001] server systemd-networkd[206]: eth1: Configuring with /etc/systemd/network/40-eth1.network.104client # [312596.031322] client systemd-networkd[186]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted105server # [312596.031506] server systemd-networkd[206]: eth1: Link UP106client # [312596.031415] client systemd-networkd[186]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted107server # [312596.031570] server systemd[1]: Started Network Management.108client # [312596.040906] client systemd-networkd[186]: /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.109server # [312596.031762] server systemd-networkd[206]: eth1: Gained carrier110client # [312596.041069] client systemd-networkd[186]: /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.111server # [312596.032773] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...112client # [312596.041226] client systemd-networkd[186]: lo: Link UP113server # [312596.085898] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.114client # [312596.041232] client systemd-networkd[186]: lo: Gained carrier115client # [312596.041427] client systemd-networkd[186]: eth1: Configuring with /etc/systemd/network/40-eth1.network.116client # [312596.042114] client systemd[1]: Started Network Management.117client # [312596.076503] client systemd-networkd[186]: eth1: Link UP118client # [312596.076568] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...119client # [312596.076816] client systemd-networkd[186]: eth1: Gained carrier120client # [312596.110767] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.121client # [312596.437174] client systemd-resolved[114]: Positive Trust Anchors:122client # [312596.437191] client systemd-resolved[114]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d123client # [312596.437195] client systemd-resolved[114]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16124client # [312596.437230] client systemd-resolved[114]: 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 # [312596.437978] client systemd-resolved[114]: Using system hostname 'client'.126client # [312596.439511] client systemd[1]: Started Network Name Resolution.127client # [312596.439616] client systemd[1]: Reached target Network.128client # [312596.439700] client systemd[1]: Reached target System Initialization.129client # [312596.439767] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container130client # [312596.439805] client systemd[1]: Started Daily Cleanup of Temporary Directories.131client # [312596.439830] client systemd[1]: Reached target Timer Units.132client # [312596.439992] client systemd[1]: Listening on D-Bus System Message Bus Socket.133client # [312596.440172] client systemd[1]: Listening on Nix Daemon Socket.134client # [312596.440299] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.135client # [312596.440326] client systemd[1]: Reached target Socket Units.136client # [312596.440373] client systemd[1]: Reached target Basic System.137client # [312596.441957] client systemd[1]: Starting Import lastlog data into lastlog2 database...138client # [312596.443028] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...139client # [312596.444630] client systemd[1]: Starting D-Bus System Message Bus...140client # [312596.499080] client systemd[1]: Finished Import lastlog data into lastlog2 database.141server # [312596.444146] server systemd-resolved[133]: Positive Trust Anchors:142server # [312596.444159] server systemd-resolved[133]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d143server # [312596.444163] server systemd-resolved[133]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16144server # [312596.444198] server systemd-resolved[133]: 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 test145server # [312596.444950] server systemd-resolved[133]: Using system hostname 'server'.146server # [312596.446401] server systemd[1]: Started Network Name Resolution.147server # [312596.446487] server systemd[1]: Reached target Network.148server # [312596.446550] server systemd[1]: Reached target Network is Online.149server # [312596.446593] server systemd[1]: Reached target System Initialization.150server # [312596.446649] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container151server # [312596.446672] server systemd[1]: Started Daily Cleanup of Temporary Directories.152server # [312596.446690] server systemd[1]: Reached target Timer Units.153server # [312596.446813] server systemd[1]: Listening on D-Bus System Message Bus Socket.154server # [312596.446931] server systemd[1]: Listening on Nix Daemon Socket.155server # [312596.447745] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).156server # [312596.448405] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).157server # [312596.448824] server systemd[1]: Listening on SSH Socket.158server # [312596.448935] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.159server # [312596.448956] server systemd[1]: Reached target Socket Units.160server # [312596.449005] server systemd[1]: Reached target Basic System.161server # [312596.480464] server systemd[1]: Starting Import lastlog data into lastlog2 database...162server # [312596.481385] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...163server # [312596.482274] server systemd[1]: Starting Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test]...164server # [312596.482307] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.165server # [312596.484198] server systemd[1]: Starting D-Bus System Message Bus...166server # [312596.538510] server systemd[1]: lastlog2-import.service: Failed to spawn executor: No such file or directory167server # [312596.538542] server systemd[1]: lastlog2-import.service: Failed to spawn 'start-post' task: No such file or directory168server # [312596.538569] server systemd[1]: lastlog2-import.service: Failed to read pids.max for control group /system.slice/lastlog2-import.service, ignoring: No data available169server # [312596.538595] server systemd[1]: lastlog2-import.service: Failed to read pids.max for control group /system.slice/lastlog2-import.service, ignoring: No data available170server # [312596.538615] server systemd[1]: lastlog2-import.service: Failed with result 'resources'.171server # [312596.538759] server systemd[1]: Failed to start Import lastlog data into lastlog2 database.172server # [312596.762186] server nsncd[213]: Oct 20 00:13:21.733 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"173server # [312596.762181] server systemd[1]: Started Name Service Cache Daemon (nsncd).174server # [312596.762303] server systemd[1]: Reached target Host and Network Name Lookups.175server # [312596.762367] server systemd[1]: Reached target User and Group Name Lookups.176server # [312596.763936] server systemd[1]: Starting User Login Management...177server # [312596.765044] server systemd[1]: Starting Permit User Sessions...178server # [312596.819296] server systemd[1]: Finished Permit User Sessions.179server # [312596.821123] server systemd[1]: Started Console Getty.180server # [312596.821203] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0181server # [312596.821253] server systemd[1]: Reached target Login Prompts.182client # [312596.783810] client systemd[1]: Started Name Service Cache Daemon (nsncd).183client # [312596.784192] client nsncd[193]: Oct 20 00:13:21.755 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"184client # [312596.783936] client systemd[1]: Reached target Host and Network Name Lookups.185client # [312596.784088] client systemd[1]: Reached target User and Group Name Lookups.186client # [312596.812739] client systemd[1]: Starting User Login Management...187client # [312596.814062] client systemd[1]: Starting Permit User Sessions...188client # [312596.824197] client systemd[1]: Finished Permit User Sessions.189client # [312596.825225] client systemd[1]: Started Console Getty.190client # [312596.825267] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0191client # [312596.825284] client systemd[1]: Reached target Login Prompts.192server # [312597.117887] server dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...193server # [312597.119442] server dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'194server # [312597.119442] server dbus-broker-launch[215]: Invalid user-name in /nix/store/rpcj23p36a9hhgvwvfil13q7cmjvw0f2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"195server # [312597.120251] server systemd[1]: Started D-Bus System Message Bus.196server # [312597.128507] server dbus-broker-launch[215]: Ready197server # [312597.197160] server systemd[1]: Started Expose sshd via dumbpipe (iroh) [p2p-ssh-iroh-test].198server # [312597.264066] server systemd-resolved[133]: Switching to fallback DNS server 1.1.1.1#one.one.one.one.199client # [312597.168952] client dbus-broker-launch[194]: Looking up NSS user entry for 'systemd-timesync'...200client # [312597.168952] client dbus-broker-launch[194]: NSS returned no entry for 'systemd-timesync'201client # [312597.168952] client dbus-broker-launch[194]: Invalid user-name in /nix/store/layimlb80gf2wr19d7177752l4mb2ysj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"202client # [312597.170168] client systemd[1]: Started D-Bus System Message Bus.203client # [312597.181089] client dbus-broker-launch[194]: Ready204server # [312597.692203] server systemd-networkd[206]: eth1: Gained IPv6LL205server # [312597.929652] server systemd-logind[228]: New seat seat0.206server # [312597.930139] server systemd[1]: Started User Login Management.207server # [312597.964826] server systemd[1]: Starting linger-users.service...208Connection to 127.0.0.1 22 port [tcp/ssh] succeeded!209server # [312597.980359] server systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available210server # [312597.980456] server systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available211server # [312597.980516] server systemd[1]: linger-users.service: Deactivated successfully.212server # [312597.980835] server systemd[1]: Finished linger-users.service.213server # [312597.981735] server systemd[1]: Reached target Multi-User System.214server: (finished: waiting for success: /nix/store/1553icvwp9ryqw3hng22rrah30jw9pn0-libressl-4.3.2-nc/bin/nc -z -v 127.0.0.1 22, in 4.16 seconds)215server # [312597.982077] server systemd[1]: Startup finished in 3.401s.216server: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service217client # [312597.911566] client systemd-logind[209]: New seat seat0.218client # [312597.911835] client systemd[1]: Started User Login Management.219client # [312597.913176] client systemd[1]: Starting linger-users.service...220client # [312597.976345] client systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available221client # [312597.976401] client systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available222client # [312597.976439] client systemd[1]: linger-users.service: Deactivated successfully.223client # [312597.976616] client systemd[1]: Finished linger-users.service.224client # [312597.977175] client systemd[1]: Reached target Multi-User System.225client # [312597.977552] client systemd[1]: Startup finished in 3.429s.226client # [312598.076176] client systemd-networkd[186]: eth1: Gained IPv6LL227server: (finished: waiting for unit p2p-ssh-iroh-p2p-ssh-iroh-test.service, in 0.02 seconds)228server: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret229server: (finished: must succeed: test -f /run/credentials/p2p-ssh-iroh-p2p-ssh-iroh-test.service/iroh-secret, in 0.01 seconds)230(finished: run the VM test script, in 4.19 seconds)231test script finished in 4.21s232cleanup233kill NspawnMachine (pid 52)234kill NspawnMachine (pid 53)235Container client terminated by signal KILL.236Container server terminated by signal KILL.237(finished: cleanup, in 0.38 seconds)