container-test-run-admin
checks.aarch64-linux.admin
· build #756
· 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)13client: Waiting for journal at /build/vm-state-client/var/log/journal...14server: systemd-nspawn running (pid 53)15server: Waiting for journal at /build/vm-state-server/var/log/journal...16(finished: start all VMs, in 0.00 seconds)17nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE18nixos-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.19nixos-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.21Note: 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.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 client on /build/vm-state-client.24░ Spawning container server on /build/vm-state-server.25client # No journal files were found.26client # No journal boot entry found for the specified boot (+0).27server # No journal files were found.28server # No journal boot entry found for the specified boot (+0).29client # [1582747.689748] client systemd-journald[72]: Journal started30client # [1582747.689809] client systemd-journald[72]: Runtime Journal (/run/log/journal/23579ee22c394dfa9a4c2fb85d9053b5) is 8M, max 2.5G, 2.4G free.31client # [1582747.704250] client systemd[1]: Finished Apply Kernel Variables.32client # [1582747.720945] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.33client # [1582747.737071] client systemd[1]: Starting Flush Journal to Persistent Storage...34client # [1582747.738049] client systemd[1]: Starting Network Name Resolution...35client # [1582747.738833] client systemd[1]: Starting Create Static Device Nodes in /dev...36client # [1582747.747165] client systemd-journald[72]: Time spent on flushing to /var/log/journal/23579ee22c394dfa9a4c2fb85d9053b5 is 1.812ms for 7 entries.37client # [1582747.747165] client systemd-journald[72]: System Journal (/var/log/journal/23579ee22c394dfa9a4c2fb85d9053b5) is 8M, max 4G, 3.9G free.38client # [1582747.772204] client systemd[1]: Finished Flush Journal to Persistent Storage.39client # [1582747.777907] client systemd[1]: Finished Create Static Device Nodes in /dev.40client # [1582747.779218] client systemd[1]: Reached target Preparation for Local File Systems.41client # [1582747.779333] client systemd[1]: Reached target Local File Systems.42client # [1582747.780232] client systemd[1]: Listening on Boot Loader Control Service Socket.43client # [1582747.780283] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container44client # [1582747.781255] client systemd[1]: Starting Save Transient machine-id to Disk...45client # [1582747.782089] client systemd[1]: Starting Create System Files and Directories...46client # [1582747.782156] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys47client # [1582747.799547] client systemd-tmpfiles[147]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted48client # [1582747.803960] client systemd-tmpfiles[147]: fchmod() of /var/log/journal failed: Operation not permitted49client # [1582747.804104] client systemd-tmpfiles[147]: fchmod() of /var/log/journal/23579ee22c394dfa9a4c2fb85d9053b5 failed: Operation not permitted50client # [1582747.804301] client systemd-tmpfiles[147]: fchmod() of /run/log/journal failed: Operation not permitted51client # [1582747.808330] client systemd[1]: Finished Create System Files and Directories.52client # [1582747.809846] client systemd[1]: Starting Rebuild Journal Catalog...53client # [1582747.810917] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...54client # [1582747.813769] client systemd[1]: Finished Save Transient machine-id to Disk.55client # [1582747.823107] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.56client # [1582747.829962] client systemd[1]: Finished Rebuild Journal Catalog.57client # [1582747.831051] client systemd[1]: Starting Update is Completed...58client # [1582747.836905] client systemd[1]: Finished Firewall.59client # [1582747.837067] client systemd[1]: Reached target Preparation for Network.60client # [1582747.837296] client systemd[1]: Listening on Network Management Resolve Hook Socket.61client # [1582747.838419] client systemd[1]: Starting Network Management...62client # [1582747.842741] client systemd[1]: Finished Update is Completed.63server # [1582747.696450] server systemd-journald[101]: Journal started64server # [1582747.696507] server systemd-journald[101]: Runtime Journal (/run/log/journal/ba16aa5467f34d9d881be0c740a8e4a9) is 8M, max 2.5G, 2.4G free.65server # [1582747.710697] server systemd[1]: Finished Apply Kernel Variables.66server # [1582747.723691] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.67server # [1582747.744518] server systemd[1]: Starting Flush Journal to Persistent Storage...68server # [1582747.745320] server systemd[1]: Starting Network Name Resolution...69server # [1582747.745951] server systemd[1]: Starting Create Static Device Nodes in /dev...70server # [1582747.753909] server systemd-journald[101]: Time spent on flushing to /var/log/journal/ba16aa5467f34d9d881be0c740a8e4a9 is 1.840ms for 7 entries.71server # [1582747.753909] server systemd-journald[101]: System Journal (/var/log/journal/ba16aa5467f34d9d881be0c740a8e4a9) is 8M, max 4G, 3.9G free.72server # [1582747.777582] server systemd[1]: Finished Flush Journal to Persistent Storage.73server # [1582747.778372] server systemd[1]: Finished Create Static Device Nodes in /dev.74server # [1582747.779710] server systemd[1]: Reached target Preparation for Local File Systems.75server # [1582747.779817] server systemd[1]: Reached target Local File Systems.76server # [1582747.780817] server systemd[1]: Listening on Boot Loader Control Service Socket.77server # [1582747.780878] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container78server # [1582747.782069] server systemd[1]: Starting Save Transient machine-id to Disk...79server # [1582747.783011] server systemd[1]: Starting Create System Files and Directories...80server # [1582747.783050] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys81server # [1582747.798780] server systemd-tmpfiles[170]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted82server # [1582747.798958] server systemd-tmpfiles[170]: fchmod() of /var/log/journal failed: Operation not permitted83server # [1582747.799079] server systemd-tmpfiles[170]: fchmod() of /var/log/journal/ba16aa5467f34d9d881be0c740a8e4a9 failed: Operation not permitted84server # [1582747.799265] server systemd-tmpfiles[170]: fchmod() of /run/log/journal failed: Operation not permitted85server # [1582747.800945] server systemd[1]: Finished Create System Files and Directories.86server # [1582747.802311] server systemd[1]: Starting Rebuild Journal Catalog...87server # [1582747.803078] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...88server # [1582747.813250] server systemd[1]: Finished Save Transient machine-id to Disk.89server # [1582747.818551] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.90server # [1582747.822845] server systemd[1]: Finished Rebuild Journal Catalog.91server # [1582747.824486] server systemd[1]: Starting Update is Completed...92server # [1582747.835992] server systemd[1]: Finished Update is Completed.93server # [1582747.854829] server systemd[1]: Finished Firewall.94server # [1582747.854993] server systemd[1]: Reached target Preparation for Network.95server # [1582747.855223] server systemd[1]: Listening on Network Management Resolve Hook Socket.96server # [1582747.856345] server systemd[1]: Starting Network Management...97server # [1582748.727090] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.98client # [1582748.708881] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.99client # [1582749.566054] client systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted100client # [1582749.566157] client systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted101client # [1582749.576286] 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.102client # [1582749.576457] 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.103client # [1582749.576653] client systemd-networkd[185]: lo: Link UP104client # [1582749.576656] client systemd-networkd[185]: lo: Gained carrier105client # [1582749.576952] client systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.106client # [1582749.577446] client systemd[1]: Started Network Management.107client # [1582749.577496] client systemd-networkd[185]: eth1: Link UP108client # [1582749.578151] client systemd-networkd[185]: eth1: Gained carrier109client # [1582749.579390] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...110client # [1582749.633626] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.111server # [1582749.566342] server systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted112server # [1582749.566432] server systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted113server # [1582749.576453] server systemd-networkd[217]: /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.114server # [1582749.576629] server systemd-networkd[217]: /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.115server # [1582749.576796] server systemd-networkd[217]: lo: Link UP116server # [1582749.576801] server systemd-networkd[217]: lo: Gained carrier117server # [1582749.576983] server systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network.118server # [1582749.577420] server systemd[1]: Started Network Management.119server # [1582749.577582] server systemd-networkd[217]: eth1: Link UP120server # [1582749.578166] server systemd-networkd[217]: eth1: Gained carrier121server # [1582749.579009] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...122server # [1582749.633902] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.123server # [1582750.723747] server systemd-resolved[142]: Positive Trust Anchors:124server # [1582750.723759] server systemd-resolved[142]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d125server # [1582750.723762] server systemd-resolved[142]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16126server # [1582750.723797] server systemd-resolved[142]: 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 # [1582750.746635] server systemd-resolved[142]: Using system hostname 'server'.128server # [1582750.748261] server systemd[1]: Started Network Name Resolution.129server # [1582750.748354] server systemd[1]: Reached target Network.130server # [1582750.748419] server systemd[1]: Reached target System Initialization.131server # [1582750.748476] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container132server # [1582750.748507] server systemd[1]: Started Daily Cleanup of Temporary Directories.133server # [1582750.748525] server systemd[1]: Reached target Timer Units.134server # [1582750.749151] server systemd[1]: Listening on D-Bus System Message Bus Socket.135server # [1582750.749420] server systemd[1]: Listening on Nix Daemon Socket.136server # [1582750.750517] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).137server # [1582750.751379] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).138server # [1582750.751908] server systemd[1]: Listening on SSH Socket.139server # [1582750.752041] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.140server # [1582750.752066] server systemd[1]: Reached target Socket Units.141server # [1582750.752109] server systemd[1]: Reached target Basic System.142server # [1582750.753299] server systemd[1]: Starting Import lastlog data into lastlog2 database...143server # [1582750.754133] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...144server # [1582750.754160] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.145server # [1582750.756277] server systemd[1]: Starting D-Bus System Message Bus...146server # [1582750.777671] server systemd[1]: Finished Import lastlog data into lastlog2 database.147client # [1582750.769785] client systemd-resolved[118]: Positive Trust Anchors:148client # [1582750.769795] client systemd-resolved[118]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d149client # [1582750.769800] client systemd-resolved[118]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16150client # [1582750.769835] client systemd-resolved[118]: 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 test151client # [1582750.792130] client systemd-resolved[118]: Using system hostname 'client'.152client # [1582750.793567] client systemd[1]: Started Network Name Resolution.153client # [1582750.793653] client systemd[1]: Reached target Network.154client # [1582750.793713] client systemd[1]: Reached target System Initialization.155client # [1582750.793771] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container156client # [1582750.793798] client systemd[1]: Started Daily Cleanup of Temporary Directories.157client # [1582750.793818] client systemd[1]: Reached target Timer Units.158client # [1582750.793935] client systemd[1]: Listening on D-Bus System Message Bus Socket.159client # [1582750.794073] client systemd[1]: Listening on Nix Daemon Socket.160client # [1582750.794202] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.161client # [1582750.794222] client systemd[1]: Reached target Socket Units.162client # [1582750.794257] client systemd[1]: Reached target Basic System.163client # [1582750.795346] client systemd[1]: Starting Import lastlog data into lastlog2 database...164client # [1582750.796368] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...165client # [1582750.797696] client systemd[1]: Starting D-Bus System Message Bus...166client # [1582750.814930] client systemd[1]: Finished Import lastlog data into lastlog2 database.167client # [1582750.820116] client systemd-networkd[185]: eth1: Gained IPv6LL168client # [1582751.289354] client nsncd[192]: Sep 22 15:06:11.274 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"169client # [1582751.289221] client systemd[1]: Started Name Service Cache Daemon (nsncd).170client # [1582751.289299] client systemd[1]: Reached target Host and Network Name Lookups.171client # [1582751.289368] client systemd[1]: Reached target User and Group Name Lookups.172client # [1582751.293901] client systemd[1]: Starting User Login Management...173client # [1582751.294806] client systemd[1]: Starting Permit User Sessions...174client # [1582751.307708] client systemd[1]: Finished Permit User Sessions.175client # [1582751.308816] client systemd[1]: Started Console Getty.176client # [1582751.308861] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0177client # [1582751.308880] client systemd[1]: Reached target Login Prompts.178client # [1582751.535892] client dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...179client # [1582751.537303] client dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'180client # [1582751.537303] client dbus-broker-launch[193]: Invalid user-name in /nix/store/gfcgx88761xp2dca7l1zr6h0sn2iiv0w-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"181client # [1582751.538164] client systemd[1]: Started D-Bus System Message Bus.182client # [1582751.546117] client dbus-broker-launch[193]: Ready183server # [1582751.248963] server nsncd[223]: Sep 22 15:06:11.234 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"184server # [1582751.249052] server systemd[1]: Started Name Service Cache Daemon (nsncd).185server # [1582751.249168] server systemd[1]: Reached target Host and Network Name Lookups.186server # [1582751.249282] server systemd[1]: Reached target User and Group Name Lookups.187server # [1582751.251337] server systemd[1]: Starting User Login Management...188server # [1582751.252698] server systemd[1]: Starting Permit User Sessions...189server # [1582751.302239] server systemd[1]: Finished Permit User Sessions.190server # [1582751.304021] server systemd[1]: Started Console Getty.191server # [1582751.304097] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0192server # [1582751.304136] server systemd[1]: Reached target Login Prompts.193server # [1582751.392239] server systemd-networkd[217]: eth1: Gained IPv6LL194server # [1582751.569708] server dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...195server # [1582751.570988] server dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'196server # [1582751.570988] server dbus-broker-launch[224]: Invalid user-name in /nix/store/v7n8gy19x22cq975gsiiw0sfwqav2swr-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"197server # [1582751.573143] server systemd[1]: Started D-Bus System Message Bus.198server # [1582751.580278] server dbus-broker-launch[224]: Ready199client: waiting for unit network-online.target200client: (finished: waiting for unit network-online.target, in 0.03 seconds)201server: waiting for unit network-online.target202server: (finished: waiting for unit network-online.target, in 0.01 seconds)203client: 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/null204client # [1582752.483906] client systemd-logind[208]: New seat seat0.205client # [1582752.484169] client systemd[1]: Started User Login Management.206server # [1582752.531252] server systemd-logind[239]: New seat seat0.207client # [1582752.485417] client systemd[1]: Starting linger-users.service...208server # [1582752.531419] server systemd[1]: Started User Login Management.209client # [1582752.499155] client systemd[1]: linger-users.service: Deactivated successfully.210server # [1582752.532555] server systemd[1]: Starting linger-users.service...211client # [1582752.499411] client systemd[1]: Finished linger-users.service.212server # [1582752.543513] server systemd[1]: linger-users.service: Deactivated successfully.213client # [1582752.499918] client systemd[1]: Reached target Multi-User System.214server # [1582752.543702] server systemd[1]: Finished linger-users.service.215client # [1582752.500140] client systemd[1]: Startup finished in 5.486s.216server # [1582752.544101] server systemd[1]: Reached target Multi-User System.217client # [1582752.635304] client systemd[1]: Reached target Network is Online.218server # [1582752.544262] server systemd[1]: Startup finished in 5.433s.219server # [1582752.649671] server systemd[1]: Reached target Network is Online.220server # [1582752.705889] server systemd[1]: Created slice Slice /system/sshd.221server # [1582752.705969] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.222server # [1582752.706938] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:51306).223client: (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.18 seconds)224(finished: run the VM test script, in 6.39 seconds)225test script finished in 6.43s226cleanup227kill NspawnMachine (pid 52)228kill NspawnMachine (pid 53)229server # [1582752.849855] server sshd-session[255]: Accepted publickey for root from 2001:db8:1::1 port 51306 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ230server # [1582752.865398] server sshd-session[259]: Received disconnect from 2001:db8:1::1 port 51306:11: disconnected by user231server # [1582752.865630] server sshd-session[259]: Disconnected from user root 2001:db8:1::1 port 51306232server # [1582752.866694] server systemd[1]: sshd@0-57367-2001:db8:1::2:22-2001:db8:1::1:51306.service: Deactivated successfully.233server # [1582753.032959] server systemd-networkd[217]: eth1: Link DOWN234server # [1582753.032974] server systemd-networkd[217]: eth1: Lost carrier235server # [1582753.080479] server systemd-networkd[217]: eth1: Lost IPv6LL address fe80::1cb6:f6ff:fe31:9a2f.236Container client terminated by signal KILL.237Container server terminated by signal KILL.238(finished: cleanup, in 0.43 seconds)