container-test-run-admin
checks.aarch64-linux.admin
· build #1006
· raw
1build01: building gqj19pgsvn7kjrz7iypixcrcgm4c0wxr-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)14client: Waiting for journal at /build/vm-state-client/var/log/journal...15server: systemd-nspawn running (pid 54)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.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 server on /build/vm-state-server.25░ Spawning container client on /build/vm-state-client.26client # No journal files were found.27client # No journal boot entry found for the specified boot (+0).28server # No journal files were found.29server # No journal boot entry found for the specified boot (+0).30client # [ 595.438507] client systemd-journald[72]: Journal started31client # [ 595.438558] client systemd-journald[72]: Runtime Journal (/run/log/journal/08e5586d38e94508b96fba877cd98810) is 8M, max 2.5G, 2.4G free.32client # [ 595.448841] client systemd[1]: Finished Apply Kernel Variables.33client # [ 595.462749] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.34client # [ 595.476275] client systemd[1]: Starting Flush Journal to Persistent Storage...35client # [ 595.477109] client systemd[1]: Starting Network Name Resolution...36client # [ 595.477811] client systemd[1]: Starting Create Static Device Nodes in /dev...37client # [ 595.484651] client systemd-journald[72]: Time spent on flushing to /var/log/journal/08e5586d38e94508b96fba877cd98810 is 1.057ms for 7 entries.38client # [ 595.484651] client systemd-journald[72]: System Journal (/var/log/journal/08e5586d38e94508b96fba877cd98810) is 8M, max 4G, 3.9G free.39client # [ 595.506379] client systemd[1]: Finished Create Static Device Nodes in /dev.40client # [ 595.507001] client systemd[1]: Reached target Preparation for Local File Systems.41client # [ 595.507106] client systemd[1]: Reached target Local File Systems.42client # [ 595.507935] client systemd[1]: Listening on Boot Loader Control Service Socket.43client # [ 595.507982] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container44client # [ 595.508768] client systemd[1]: Starting Save Transient machine-id to Disk...45client # [ 595.508804] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys46client # [ 595.571502] client systemd[1]: Finished Firewall.47client # [ 595.571664] client systemd[1]: Reached target Preparation for Network.48client # [ 595.571874] client systemd[1]: Listening on Network Management Resolve Hook Socket.49client # [ 595.573081] client systemd[1]: Starting Network Management...50client # [ 595.645942] client systemd[1]: Finished Flush Journal to Persistent Storage.51client # [ 595.647805] client systemd[1]: Starting Create System Files and Directories...52client # [ 595.665151] client systemd-tmpfiles[179]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted53client # [ 595.665355] client systemd-tmpfiles[179]: fchmod() of /var/log/journal failed: Operation not permitted54client # [ 595.665494] client systemd-tmpfiles[179]: fchmod() of /var/log/journal/08e5586d38e94508b96fba877cd98810 failed: Operation not permitted55client # [ 595.665712] client systemd-tmpfiles[179]: fchmod() of /run/log/journal failed: Operation not permitted56client # [ 595.667142] client systemd[1]: Finished Create System Files and Directories.57client # [ 595.668341] client systemd[1]: Starting Rebuild Journal Catalog...58client # [ 595.669081] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...59client # [ 595.679676] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.60client # [ 595.686853] client systemd[1]: Finished Rebuild Journal Catalog.61client # [ 595.687922] client systemd[1]: Starting Update is Completed...62client # [ 595.697690] client systemd[1]: Finished Update is Completed.63server # [ 595.437779] server systemd-journald[101]: Journal started64server # [ 595.437838] server systemd-journald[101]: Runtime Journal (/run/log/journal/34d410b7121c47b2b827ba5ef967edb6) is 8M, max 2.5G, 2.4G free.65server # [ 595.448815] server systemd[1]: Finished Apply Kernel Variables.66server # [ 595.460627] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.67server # [ 595.473759] server systemd[1]: Starting Flush Journal to Persistent Storage...68server # [ 595.474591] server systemd[1]: Starting Network Name Resolution...69server # [ 595.475273] server systemd[1]: Starting Create Static Device Nodes in /dev...70server # [ 595.482470] server systemd-journald[101]: Time spent on flushing to /var/log/journal/34d410b7121c47b2b827ba5ef967edb6 is 1.082ms for 7 entries.71server # [ 595.482470] server systemd-journald[101]: System Journal (/var/log/journal/34d410b7121c47b2b827ba5ef967edb6) is 8M, max 4G, 3.9G free.72server # [ 595.505842] server systemd[1]: Finished Create Static Device Nodes in /dev.73server # [ 595.506419] server systemd[1]: Reached target Preparation for Local File Systems.74server # [ 595.506523] server systemd[1]: Reached target Local File Systems.75server # [ 595.507389] server systemd[1]: Listening on Boot Loader Control Service Socket.76server # [ 595.507433] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container77server # [ 595.508329] server systemd[1]: Starting Save Transient machine-id to Disk...78server # [ 595.508359] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys79server # [ 595.576573] server systemd[1]: Finished Firewall.80server # [ 595.576712] server systemd[1]: Reached target Preparation for Network.81server # [ 595.576920] server systemd[1]: Listening on Network Management Resolve Hook Socket.82server # [ 595.577860] server systemd[1]: Starting Network Management...83server # [ 595.641178] server systemd[1]: Finished Flush Journal to Persistent Storage.84server # [ 595.642894] server systemd[1]: Starting Create System Files and Directories...85server # [ 595.657729] server systemd-tmpfiles[210]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted86server # [ 595.657898] server systemd-tmpfiles[210]: fchmod() of /var/log/journal failed: Operation not permitted87server # [ 595.658014] server systemd-tmpfiles[210]: fchmod() of /var/log/journal/34d410b7121c47b2b827ba5ef967edb6 failed: Operation not permitted88server # [ 595.658177] server systemd-tmpfiles[210]: fchmod() of /run/log/journal failed: Operation not permitted89server # [ 595.663475] server systemd[1]: Finished Create System Files and Directories.90server # [ 595.665219] server systemd[1]: Starting Rebuild Journal Catalog...91server # [ 595.665995] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...92server # [ 595.677216] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.93server # [ 595.684115] server systemd[1]: Finished Rebuild Journal Catalog.94server # [ 595.685181] server systemd[1]: Starting Update is Completed...95server # [ 595.695209] server systemd[1]: Finished Update is Completed.96server # [ 596.328340] server systemd-networkd[206]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted97server # [ 596.328436] server systemd-networkd[206]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted98server # [ 596.337478] 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.99server # [ 596.337647] 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.100server # [ 596.337811] server systemd-networkd[206]: lo: Link UP101server # [ 596.337815] server systemd-networkd[206]: lo: Gained carrier102server # [ 596.338013] server systemd-networkd[206]: eth1: Configuring with /etc/systemd/network/40-eth1.network.103server # [ 596.338365] server systemd[1]: Started Network Management.104server # [ 596.380380] server systemd-networkd[206]: eth1: Link UP105client # [ 596.316159] client systemd-networkd[175]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted106server # [ 596.380466] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...107client # [ 596.316254] client systemd-networkd[175]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted108server # [ 596.380681] server systemd-networkd[206]: eth1: Gained carrier109client # [ 596.325175] client systemd-networkd[175]: /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.110server # [ 596.434441] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.111client # [ 596.325346] client systemd-networkd[175]: /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.112client # [ 596.325506] client systemd-networkd[175]: lo: Link UP113client # [ 596.325510] client systemd-networkd[175]: lo: Gained carrier114client # [ 596.325707] client systemd-networkd[175]: eth1: Configuring with /etc/systemd/network/40-eth1.network.115client # [ 596.326180] client systemd-networkd[175]: eth1: Link UP116client # [ 596.326490] client systemd-networkd[175]: eth1: Gained carrier117client # [ 596.327478] client systemd[1]: Started Network Management.118client # [ 596.328603] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...119client # [ 596.387460] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.120server # [ 596.746244] server systemd-resolved[139]: Positive Trust Anchors:121server # [ 596.746255] server systemd-resolved[139]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d122server # [ 596.746258] server systemd-resolved[139]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16123client # [ 596.748639] client systemd-resolved[112]: Positive Trust Anchors:124client # [ 596.748649] client systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d125client # [ 596.748653] client systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16126client # [ 596.748687] client systemd-resolved[112]: 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 test127client # [ 596.768125] client systemd-resolved[112]: Using system hostname 'client'.128client # [ 596.769318] client systemd[1]: Started Network Name Resolution.129client # [ 596.769386] client systemd[1]: Reached target Network.130client # [ 596.769449] client systemd[1]: Reached target System Initialization.131client # [ 596.769497] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container132client # [ 596.769523] client systemd[1]: Started Daily Cleanup of Temporary Directories.133client # [ 596.769543] client systemd[1]: Reached target Timer Units.134client # [ 596.769657] client systemd[1]: Listening on D-Bus System Message Bus Socket.135client # [ 597.436193] client systemd-networkd[175]: eth1: Gained IPv6LL136client # [ 597.483337] client systemd[1]: Listening on Nix Daemon Socket.137client # [ 597.483526] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.138client # [ 597.483551] client systemd[1]: Reached target Socket Units.139client # [ 597.483601] client systemd[1]: Reached target Basic System.140client # [ 597.485113] client systemd[1]: Starting Import lastlog data into lastlog2 database...141client # [ 597.486063] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...142client # [ 597.487345] client systemd[1]: Starting D-Bus System Message Bus...143client # [ 597.531090] client systemd[1]: Finished Import lastlog data into lastlog2 database.144client # [ 597.696234] client systemd[1]: Started Name Service Cache Daemon (nsncd).145client # [ 597.696304] client systemd[1]: Reached target Host and Network Name Lookups.146client # [ 597.696367] client systemd[1]: Reached target User and Group Name Lookups.147client # [ 597.696568] client nsncd[191]: Oct 16 09:33:22.668 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"148client # [ 597.697957] client systemd[1]: Starting User Login Management...149client # [ 597.698675] client systemd[1]: Starting Permit User Sessions...150server # [ 596.746292] server systemd-resolved[139]: 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 test151server # [ 596.766112] server systemd-resolved[139]: Using system hostname 'server'.152server # [ 596.767405] server systemd[1]: Started Network Name Resolution.153server # [ 596.767486] server systemd[1]: Reached target Network.154server # [ 596.767549] server systemd[1]: Reached target System Initialization.155server # [ 596.767598] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container156server # [ 596.767624] server systemd[1]: Started Daily Cleanup of Temporary Directories.157server # [ 596.767641] server systemd[1]: Reached target Timer Units.158server # [ 596.767755] server systemd[1]: Listening on D-Bus System Message Bus Socket.159server # [ 597.468195] server systemd-networkd[206]: eth1: Gained IPv6LL160server # [ 597.483774] server systemd[1]: Listening on Nix Daemon Socket.161server # [ 597.486626] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).162server # [ 597.489196] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).163server # [ 597.490467] server systemd[1]: Listening on SSH Socket.164server # [ 597.490714] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.165server # [ 597.490766] server systemd[1]: Reached target Socket Units.166server # [ 597.490865] server systemd[1]: Reached target Basic System.167server # [ 597.512605] server systemd[1]: Starting Import lastlog data into lastlog2 database...168server # [ 597.514296] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...169server # [ 597.514350] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.170server # [ 597.517782] server systemd[1]: Starting D-Bus System Message Bus...171server # [ 597.533012] server systemd[1]: Finished Import lastlog data into lastlog2 database.172server # [ 597.709752] server nsncd[222]: Oct 16 09:33:22.681 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"173server # [ 597.709819] server systemd[1]: Started Name Service Cache Daemon (nsncd).174server # [ 597.709938] server systemd[1]: Reached target Host and Network Name Lookups.175server # [ 597.710065] server systemd[1]: Reached target User and Group Name Lookups.176server # [ 597.740398] server systemd[1]: Starting User Login Management...177client # [ 597.746491] client systemd[1]: Finished Permit User Sessions.178server # [ 597.741224] server systemd[1]: Starting Permit User Sessions...179client # [ 597.747588] client systemd[1]: Started Console Getty.180server # [ 597.750358] server systemd[1]: Finished Permit User Sessions.181client # [ 597.747636] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0182server # [ 597.751341] server systemd[1]: Started Console Getty.183client # [ 597.747657] client systemd[1]: Reached target Login Prompts.184server # [ 597.751393] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0185client # [ 597.860157] client dbus-broker-launch[192]: Looking up NSS user entry for 'systemd-timesync'...186client # [ 597.861010] client dbus-broker-launch[192]: NSS returned no entry for 'systemd-timesync'187client # [ 597.861010] client dbus-broker-launch[192]: Invalid user-name in /nix/store/3b7ivjy4avg8mhi9dg4k18x5vz316ar1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"188client # [ 597.861429] client systemd[1]: Started D-Bus System Message Bus.189client # [ 597.868320] client dbus-broker-launch[192]: Ready190server # [ 597.751416] server systemd[1]: Reached target Login Prompts.191server # [ 597.863319] server dbus-broker-launch[223]: Looking up NSS user entry for 'systemd-timesync'...192server # [ 597.864134] server dbus-broker-launch[223]: NSS returned no entry for 'systemd-timesync'193server # [ 597.864134] server dbus-broker-launch[223]: Invalid user-name in /nix/store/fcv7s1kgqsb52z4a6rwfmvbwvv0z99y2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"194server # [ 597.864538] server systemd[1]: Started D-Bus System Message Bus.195server # [ 597.871205] server dbus-broker-launch[223]: Ready196client # [ 598.679073] client systemd-logind[207]: New seat seat0.197server # [ 598.679430] server systemd-logind[238]: New seat seat0.198client # [ 598.679248] client systemd[1]: Started User Login Management.199server # [ 598.679651] server systemd[1]: Started User Login Management.200client # [ 598.680398] client systemd[1]: Starting linger-users.service...201server # [ 598.681864] server systemd[1]: Starting linger-users.service...202client # [ 598.732338] client systemd[1]: linger-users.service: Deactivated successfully.203server # [ 598.731793] server systemd[1]: linger-users.service: Deactivated successfully.204client # [ 598.732423] client systemd[1]: Finished linger-users.service.205server # [ 598.732032] server systemd[1]: Finished linger-users.service.206client # [ 598.732847] client systemd[1]: Reached target Multi-User System.207server # [ 598.732467] server systemd[1]: Reached target Multi-User System.208client: waiting for unit network-online.target209client: (finished: waiting for unit network-online.target, in 0.02 seconds)210server: waiting for unit network-online.target211server: (finished: waiting for unit network-online.target, in 0.01 seconds)212client: 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/null213server # [ 603.796703] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.214server # [ 603.832261] server systemd[1]: Finished Save Transient machine-id to Disk.215server # [ 603.837503] server systemd[1]: Startup finished in 8.979s.216server # [ 603.953151] server systemd[1]: Reached target Network is Online.217server # [ 603.997425] server systemd[1]: Created slice Slice /system/sshd.218server # [ 603.997501] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.219server # [ 603.998452] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:47650).220client # [ 603.796824] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.221client # [ 603.832269] client systemd[1]: Finished Save Transient machine-id to Disk.222client # [ 603.832454] client systemd[1]: Startup finished in 9.040s.223client # [ 603.941685] client systemd[1]: Reached target Network is Online.224client: (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.15 seconds)225(finished: run the VM test script, in 9.84 seconds)226test script finished in 9.90s227cleanup228kill NspawnMachine (pid 52)229kill NspawnMachine (pid 54)230Container client terminated by signal KILL.231server # [ 604.120347] server sshd-session[255]: Accepted publickey for root from 2001:db8:1::1 port 47650 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ232server # [ 604.134036] server sshd-session[259]: Received disconnect from 2001:db8:1::1 port 47650:11: disconnected by user233server # [ 604.134206] server sshd-session[259]: Disconnected from user root 2001:db8:1::1 port 47650234server # [ 604.135251] server systemd[1]: sshd@0-1-2001:db8:1::2:22-2001:db8:1::1:47650.service: Deactivated successfully.235server # [ 604.312763] server systemd-networkd[206]: eth1: Link DOWN236server # [ 604.312789] server systemd-networkd[206]: eth1: Lost carrier237server # [ 604.344742] server systemd-networkd[206]: eth1: Lost IPv6LL address fe80::a422:55ff:fe08:b94c.238(finished: cleanup, in 0.33 seconds)239Container server terminated by signal KILL.