container-test-run-admin
checks.aarch64-linux.admin
· build #810
· raw
1build01: building bl6vvx351yzxmxf02w20qszz08246f6j-container-test-run-admin.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)18nixos-nspawn(server): TAP vde-tap1 not found; container will be isolated from VDE19nixos-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.20nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.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.23░ Spawning container server on /build/vm-state-server.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.26client # No journal boot entry found for the specified boot (+0).27server # Journal file /build/vm-state-server/var/log/journal/0b9a3c47add4492ca2c7d962d4875659/system.journal is truncated, ignoring file.28server # No journal boot entry found for the specified boot (+0).29client # [2258314.314136] client systemd-journald[72]: Journal started30client # [2258314.314191] client systemd-journald[72]: Runtime Journal (/run/log/journal/6bc4ad615a3741a09d3cb07a51afe5b7) is 8M, max 2.5G, 2.4G free.31client # [2258314.332234] client systemd[1]: Finished Apply Kernel Variables.32client # [2258314.345701] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.33client # [2258314.358802] client systemd[1]: Starting Flush Journal to Persistent Storage...34client # [2258314.359729] client systemd[1]: Starting Network Name Resolution...35client # [2258314.360424] client systemd[1]: Starting Create Static Device Nodes in /dev...36client # [2258314.371138] client systemd-journald[72]: Time spent on flushing to /var/log/journal/6bc4ad615a3741a09d3cb07a51afe5b7 is 2.748ms for 7 entries.37client # [2258314.371138] client systemd-journald[72]: System Journal (/var/log/journal/6bc4ad615a3741a09d3cb07a51afe5b7) is 8M, max 4G, 3.9G free.38client # [2258314.378384] client systemd[1]: Finished Create Static Device Nodes in /dev.39client # [2258314.378983] client systemd[1]: Reached target Preparation for Local File Systems.40client # [2258314.379104] client systemd[1]: Reached target Local File Systems.41client # [2258314.380016] client systemd[1]: Listening on Boot Loader Control Service Socket.42client # [2258314.380069] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container43client # [2258314.380967] client systemd[1]: Starting Save Transient machine-id to Disk...44client # [2258314.381004] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys45client # [2258314.426141] client systemd[1]: Finished Flush Journal to Persistent Storage.46client # [2258314.427651] client systemd[1]: Starting Create System Files and Directories...47client # [2258314.446784] client systemd-tmpfiles[145]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted48client # [2258314.447004] client systemd-tmpfiles[145]: fchmod() of /var/log/journal failed: Operation not permitted49client # [2258314.447161] client systemd-tmpfiles[145]: fchmod() of /var/log/journal/6bc4ad615a3741a09d3cb07a51afe5b7 failed: Operation not permitted50client # [2258314.447392] client systemd-tmpfiles[145]: fchmod() of /run/log/journal failed: Operation not permitted51client # [2258314.448837] client systemd[1]: Finished Create System Files and Directories.52client # [2258314.449863] client systemd[1]: Starting Rebuild Journal Catalog...53client # [2258314.450682] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...54client # [2258314.465384] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.55client # [2258314.471711] client systemd[1]: Finished Rebuild Journal Catalog.56client # [2258314.472894] client systemd[1]: Starting Update is Completed...57client # [2258314.483975] client systemd[1]: Finished Update is Completed.58client # [2258314.493793] client systemd[1]: Finished Firewall.59client # [2258314.494039] client systemd[1]: Reached target Preparation for Network.60client # [2258314.494437] client systemd[1]: Listening on Network Management Resolve Hook Socket.61client # [2258314.496118] client systemd[1]: Starting Network Management...62client # [2258315.208643] client systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted63client # [2258315.208736] client systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted64client # [2258315.229380] 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.65client # [2258315.229549] 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.66client # [2258315.229718] client systemd-networkd[185]: lo: Link UP67client # [2258315.229723] client systemd-networkd[185]: lo: Gained carrier68client # [2258315.229929] client systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.69client # [2258315.230319] client systemd[1]: Started Network Management.70client # [2258315.230384] client systemd-networkd[185]: eth1: Link UP71client # [2258315.230732] client systemd-networkd[185]: eth1: Gained carrier72client # [2258315.231590] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...73client # [2258315.266089] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.74client # [2258315.345787] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.75client # [2258315.346764] client systemd[1]: Finished Save Transient machine-id to Disk.76server # [2258314.334433] server systemd-journald[101]: Journal started77server # [2258314.334488] server systemd-journald[101]: Runtime Journal (/run/log/journal/0b9a3c47add4492ca2c7d962d4875659) is 8M, max 2.5G, 2.4G free.78server # [2258314.343475] server systemd[1]: Finished Apply Kernel Variables.79server # [2258314.355049] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.80server # [2258314.368372] server systemd[1]: Starting Flush Journal to Persistent Storage...81server # [2258314.369146] server systemd[1]: Starting Network Name Resolution...82server # [2258314.369792] server systemd[1]: Starting Create Static Device Nodes in /dev...83server # [2258314.377474] server systemd-journald[101]: Time spent on flushing to /var/log/journal/0b9a3c47add4492ca2c7d962d4875659 is 1.127ms for 7 entries.84server # [2258314.377474] server systemd-journald[101]: System Journal (/var/log/journal/0b9a3c47add4492ca2c7d962d4875659) is 8M, max 4G, 3.9G free.85server # [2258314.396378] server systemd[1]: Finished Create Static Device Nodes in /dev.86server # [2258314.396963] server systemd[1]: Reached target Preparation for Local File Systems.87server # [2258314.397067] server systemd[1]: Reached target Local File Systems.88server # [2258314.397896] server systemd[1]: Listening on Boot Loader Control Service Socket.89server # [2258314.397945] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container90server # [2258314.398746] server systemd[1]: Starting Save Transient machine-id to Disk...91server # [2258314.398782] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys92server # [2258314.406813] server systemd[1]: Finished Flush Journal to Persistent Storage.93server # [2258314.408590] server systemd[1]: Starting Create System Files and Directories...94server # [2258314.428391] server systemd-tmpfiles[156]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted95server # [2258314.428675] server systemd-tmpfiles[156]: fchmod() of /var/log/journal failed: Operation not permitted96server # [2258314.428831] server systemd-tmpfiles[156]: fchmod() of /var/log/journal/0b9a3c47add4492ca2c7d962d4875659 failed: Operation not permitted97server # [2258314.429064] server systemd-tmpfiles[156]: fchmod() of /run/log/journal failed: Operation not permitted98server # [2258314.430492] server systemd[1]: Finished Create System Files and Directories.99server # [2258314.431562] server systemd[1]: Starting Rebuild Journal Catalog...100server # [2258314.432306] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...101server # [2258314.446493] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.102server # [2258314.454846] server systemd[1]: Finished Rebuild Journal Catalog.103server # [2258314.456138] server systemd[1]: Starting Update is Completed...104server # [2258314.467716] server systemd[1]: Finished Update is Completed.105server # [2258314.514726] server systemd[1]: Finished Firewall.106server # [2258314.514883] server systemd[1]: Reached target Preparation for Network.107server # [2258314.515101] server systemd[1]: Listening on Network Management Resolve Hook Socket.108server # [2258314.516234] server systemd[1]: Starting Network Management...109server # [2258315.217386] server systemd-networkd[216]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted110server # [2258315.217492] server systemd-networkd[216]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted111server # [2258315.232423] server systemd-networkd[216]: /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.112server # [2258315.232598] server systemd-networkd[216]: /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.113server # [2258315.232753] server systemd-networkd[216]: lo: Link UP114server # [2258315.232757] server systemd-networkd[216]: lo: Gained carrier115server # [2258315.232929] server systemd-networkd[216]: eth1: Configuring with /etc/systemd/network/40-eth1.network.116server # [2258315.233407] server systemd[1]: Started Network Management.117server # [2258315.233416] server systemd-networkd[216]: eth1: Link UP118server # [2258315.233747] server systemd-networkd[216]: eth1: Gained carrier119server # [2258315.235018] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...120server # [2258315.274017] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.121server # [2258315.338669] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.122server # [2258315.363201] server systemd[1]: Finished Save Transient machine-id to Disk.123server # [2258315.620181] server systemd-resolved[131]: Positive Trust Anchors:124server # [2258315.620192] server systemd-resolved[131]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d125server # [2258315.620196] server systemd-resolved[131]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16126server # [2258315.620231] server systemd-resolved[131]: 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 test127server # [2258315.641570] server systemd-resolved[131]: Using system hostname 'server'.128server # [2258315.643067] server systemd[1]: Started Network Name Resolution.129server # [2258315.643173] server systemd[1]: Reached target Network.130server # [2258315.643274] server systemd[1]: Reached target System Initialization.131server # [2258315.643343] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container132server # [2258315.643381] server systemd[1]: Started Daily Cleanup of Temporary Directories.133server # [2258315.643408] server systemd[1]: Reached target Timer Units.134server # [2258315.643667] server systemd[1]: Listening on D-Bus System Message Bus Socket.135server # [2258315.643838] server systemd[1]: Listening on Nix Daemon Socket.136server # [2258315.645550] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).137server # [2258315.646982] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).138server # [2258315.647690] server systemd[1]: Listening on SSH Socket.139server # [2258315.647846] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.140server # [2258315.647877] server systemd[1]: Reached target Socket Units.141server # [2258315.647939] server systemd[1]: Reached target Basic System.142server # [2258315.649346] server systemd[1]: Starting Import lastlog data into lastlog2 database...143server # [2258315.650445] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...144server # [2258315.650474] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.145server # [2258315.652044] server systemd[1]: Starting D-Bus System Message Bus...146server # [2258315.668441] server systemd[1]: Finished Import lastlog data into lastlog2 database.147server # [2258315.893311] server nsncd[223]: Sep 30 10:45:35.879 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"148server # [2258315.893321] server systemd[1]: Started Name Service Cache Daemon (nsncd).149server # [2258315.893426] server systemd[1]: Reached target Host and Network Name Lookups.150server # [2258315.893542] server systemd[1]: Reached target User and Group Name Lookups.151client # [2258315.643533] client systemd-resolved[108]: Positive Trust Anchors:152client # [2258315.643546] client systemd-resolved[108]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d153client # [2258315.643551] client systemd-resolved[108]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16154client # [2258315.643586] client systemd-resolved[108]: 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 test155client # [2258315.664822] client systemd-resolved[108]: Using system hostname 'client'.156client # [2258315.666320] client systemd[1]: Started Network Name Resolution.157client # [2258315.666423] client systemd[1]: Reached target Network.158client # [2258315.666515] client systemd[1]: Reached target System Initialization.159client # [2258315.666566] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container160client # [2258315.666590] client systemd[1]: Started Daily Cleanup of Temporary Directories.161client # [2258315.666608] client systemd[1]: Reached target Timer Units.162client # [2258315.666749] client systemd[1]: Listening on D-Bus System Message Bus Socket.163client # [2258315.666888] client systemd[1]: Listening on Nix Daemon Socket.164client # [2258315.667000] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.165client # [2258315.667021] client systemd[1]: Reached target Socket Units.166client # [2258315.667062] client systemd[1]: Reached target Basic System.167client # [2258315.668441] client systemd[1]: lastlog2-import.service: Failed to spawn executor: No such file or directory168client # [2258315.668470] client systemd[1]: lastlog2-import.service: Failed to spawn 'start' task: No such file or directory169client # [2258315.668504] client systemd[1]: lastlog2-import.service: Failed with result 'resources'.170client # [2258315.668569] client systemd[1]: Failed to start Import lastlog data into lastlog2 database.171client # [2258315.669790] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...172client # [2258315.670974] client systemd[1]: Starting D-Bus System Message Bus...173client # [2258315.923819] client systemd[1]: Started Name Service Cache Daemon (nsncd).174client # [2258315.923910] client systemd[1]: Reached target Host and Network Name Lookups.175client # [2258315.924108] client nsncd[192]: Sep 30 10:45:35.909 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"176server # [2258315.895594] server systemd[1]: Starting User Login Management...177server # [2258315.897069] server systemd[1]: Starting Permit User Sessions...178server # [2258315.952278] server systemd[1]: Finished Permit User Sessions.179server # [2258315.953967] server systemd[1]: Started Console Getty.180server # [2258315.954023] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0181server # [2258315.954046] server systemd[1]: Reached target Login Prompts.182server # [2258316.048737] server dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...183server # [2258316.050579] server dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'184server # [2258316.050579] server dbus-broker-launch[224]: Invalid user-name in /nix/store/j6r8dcbfj6nxgz6mzmwqzcrjya5q7k6y-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"185server # [2258316.051074] server systemd[1]: Started D-Bus System Message Bus.186server # [2258316.059731] server dbus-broker-launch[224]: Ready187client # [2258315.924049] client systemd[1]: Reached target User and Group Name Lookups.188client # [2258315.945381] client systemd[1]: Starting User Login Management...189client # [2258315.946725] client systemd[1]: Starting Permit User Sessions...190client # [2258315.958149] client systemd[1]: Finished Permit User Sessions.191client # [2258315.959717] client systemd[1]: Started Console Getty.192client # [2258315.959795] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0193client # [2258315.959836] client systemd[1]: Reached target Login Prompts.194client # [2258316.040236] client dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...195client # [2258316.041937] client dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'196client # [2258316.041937] client dbus-broker-launch[193]: Invalid user-name in /nix/store/0iqh9gajij3bbas7z5yqdlys3qgc2pb8-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"197client # [2258316.042491] client systemd[1]: Started D-Bus System Message Bus.198client # [2258316.049249] client dbus-broker-launch[193]: Ready199client # [2258316.724111] client systemd-logind[205]: New seat seat0.200client # [2258316.724265] client systemd[1]: Started User Login Management.201client # [2258316.728619] client systemd[1]: Starting linger-users.service...202client # [2258316.742805] client systemd[1]: linger-users.service: Deactivated successfully.203client # [2258316.743083] client systemd[1]: Finished linger-users.service.204client # [2258316.743893] client systemd[1]: Reached target Multi-User System.205client # [2258316.744232] client systemd[1]: Startup finished in 2.867s.206client # [2258317.024211] client systemd[1]: Reached target Network is Online.207server # [2258316.696899] server systemd-logind[239]: New seat seat0.208server # [2258316.697087] server systemd[1]: Started User Login Management.209server # [2258316.699063] server systemd[1]: Starting linger-users.service...210server # [2258316.704115] server systemd-networkd[216]: eth1: Gained IPv6LL211server # [2258316.738080] server systemd[1]: linger-users.service: Deactivated successfully.212server # [2258316.738339] server systemd[1]: Finished linger-users.service.213server # [2258316.739195] server systemd[1]: Reached target Multi-User System.214server # [2258316.739517] server systemd[1]: Startup finished in 2.781s.215server # [2258317.039941] server systemd[1]: Reached target Network is Online.216client: waiting for unit network-online.target217client: (finished: waiting for unit network-online.target, in 0.03 seconds)218server: waiting for unit network-online.target219server: (finished: waiting for unit network-online.target, in 0.02 seconds)220client: must succeed: ssh -F /dev/null -i /etc/private-test-key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes root@server true &>/dev/null221client: (finished: must succeed: ssh -F /dev/null -i /etc/private-test-key -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes root@server true &>/dev/null, in 0.13 seconds)222(finished: run the VM test script, in 3.85 seconds)223test script finished in 3.90s224cleanup225kill NspawnMachine (pid 52)226client # [2258317.184137] client systemd-networkd[185]: eth1: Gained IPv6LL227server # [2258317.112931] server systemd[1]: Created slice Slice /system/sshd.228server # [2258317.113084] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.229server # [2258317.114763] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:45462).230server # [2258317.203573] server sshd-session[255]: Accepted publickey for root from 2001:db8:1::1 port 45462 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ231server # [2258317.220144] server sshd-session[259]: Received disconnect from 2001:db8:1::1 port 45462:11: disconnected by user232server # [2258317.220304] server sshd-session[259]: Disconnected from user root 2001:db8:1::1 port 45462233server # [2258317.221960] server systemd[1]: sshd@0-225311-2001:db8:1::2:22-2001:db8:1::1:45462.service: Deactivated successfully.234kill NspawnMachine (pid 53)235Container client terminated by signal KILL.236Container server terminated by signal KILL.237(finished: cleanup, in 0.38 seconds)