container-test-run-admin
checks.aarch64-linux.admin
· build #692
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 client, server,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12client: systemd-nspawn running (pid 52)13server: systemd-nspawn running (pid 53)14client: Waiting for journal at /build/vm-state-client/var/log/journal...15server: Waiting for journal at /build/vm-state-server/var/log/journal...16(finished: start all VMs, in 0.00 seconds)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.22░ Spawning container client on /build/vm-state-client.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.25client # No journal boot entry found for the specified boot (+0).26server # No journal files were found.27server # No journal boot entry found for the specified boot (+0).28client # [1201388.194260] client systemd-journald[72]: Journal started29client # [1201388.194316] client systemd-journald[72]: Runtime Journal (/run/log/journal/36de0da990be4db68b65c6f25348040c) is 8M, max 2.5G, 2.4G free.30client # [1201388.197026] client systemd[1]: Finished Apply Kernel Variables.31client # [1201388.208036] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.32client # [1201388.221196] client systemd[1]: Starting Flush Journal to Persistent Storage...33client # [1201388.221978] client systemd[1]: Starting Network Name Resolution...34client # [1201388.222705] client systemd[1]: Starting Create Static Device Nodes in /dev...35client # [1201388.233437] client systemd-journald[72]: Time spent on flushing to /var/log/journal/36de0da990be4db68b65c6f25348040c is 1.574ms for 7 entries.36client # [1201388.233437] client systemd-journald[72]: System Journal (/var/log/journal/36de0da990be4db68b65c6f25348040c) is 8M, max 4G, 3.9G free.37client # [1201388.242937] client systemd[1]: Finished Create Static Device Nodes in /dev.38client # [1201388.243613] client systemd[1]: Reached target Preparation for Local File Systems.39client # [1201388.243742] client systemd[1]: Reached target Local File Systems.40client # [1201388.244718] client systemd[1]: Listening on Boot Loader Control Service Socket.41client # [1201388.244768] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container42client # [1201388.245606] client systemd[1]: Starting Save Transient machine-id to Disk...43client # [1201388.245638] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys44client # [1201388.332670] client systemd[1]: Finished Flush Journal to Persistent Storage.45client # [1201388.333723] client systemd[1]: Starting Create System Files and Directories...46client # [1201388.346651] client systemd[1]: Finished Firewall.47client # [1201388.346816] client systemd[1]: Reached target Preparation for Network.48client # [1201388.347044] client systemd[1]: Listening on Network Management Resolve Hook Socket.49client # [1201388.348212] client systemd[1]: Starting Network Management...50client # [1201388.352496] client systemd-tmpfiles[170]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted51client # [1201388.352722] client systemd-tmpfiles[170]: fchmod() of /var/log/journal failed: Operation not permitted52client # [1201388.352882] client systemd-tmpfiles[170]: fchmod() of /var/log/journal/36de0da990be4db68b65c6f25348040c failed: Operation not permitted53client # [1201388.353119] client systemd-tmpfiles[170]: fchmod() of /run/log/journal failed: Operation not permitted54server # [1201388.257515] server systemd-journald[101]: Journal started55server # [1201388.257571] server systemd-journald[101]: Runtime Journal (/run/log/journal/ec245d2b4d77458e929ff7ee9da4be24) is 8M, max 2.5G, 2.4G free.56server # [1201388.289898] server systemd[1]: Finished Apply Kernel Variables.57client # [1201388.354798] client systemd[1]: Finished Create System Files and Directories.58client # [1201388.356087] client systemd[1]: Starting Rebuild Journal Catalog...59server # [1201388.302696] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.60client # [1201388.356942] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...61server # [1201388.316796] server systemd[1]: Starting Flush Journal to Persistent Storage...62client # [1201388.369281] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.63server # [1201388.317609] server systemd[1]: Starting Network Name Resolution...64client # [1201388.376223] client systemd[1]: Finished Rebuild Journal Catalog.65server # [1201388.318317] server systemd[1]: Starting Create Static Device Nodes in /dev...66client # [1201388.377264] client systemd[1]: Starting Update is Completed...67server # [1201388.325968] server systemd-journald[101]: Time spent on flushing to /var/log/journal/ec245d2b4d77458e929ff7ee9da4be24 is 1.553ms for 7 entries.68client # [1201388.387342] client systemd[1]: Finished Update is Completed.69server # [1201388.325968] server systemd-journald[101]: System Journal (/var/log/journal/ec245d2b4d77458e929ff7ee9da4be24) is 8M, max 4G, 3.9G free.70client # [1201389.005076] client systemd-networkd[178]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted71server # [1201388.335701] server systemd[1]: Finished Create Static Device Nodes in /dev.72client # [1201389.005169] client systemd-networkd[178]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted73server # [1201388.336390] server systemd[1]: Reached target Preparation for Local File Systems.74client # [1201389.014576] client systemd-networkd[178]: /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.75server # [1201388.336520] server systemd[1]: Reached target Local File Systems.76client # [1201389.014744] client systemd-networkd[178]: /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.77server # [1201388.337351] server systemd[1]: Listening on Boot Loader Control Service Socket.78client # [1201389.014915] client systemd-networkd[178]: lo: Link UP79server # [1201388.337398] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container80client # [1201389.014919] client systemd-networkd[178]: lo: Gained carrier81server # [1201388.338182] server systemd[1]: Starting Save Transient machine-id to Disk...82client # [1201389.015092] client systemd-networkd[178]: eth1: Configuring with /etc/systemd/network/40-eth1.network.83server # [1201388.338219] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys84client # [1201389.015567] client systemd[1]: Started Network Management.85server # [1201388.355933] server systemd[1]: Finished Flush Journal to Persistent Storage.86client # [1201389.015596] client systemd-networkd[178]: eth1: Link UP87server # [1201388.358386] server systemd[1]: Starting Create System Files and Directories...88client # [1201389.015923] client systemd-networkd[178]: eth1: Gained carrier89server # [1201388.372704] server systemd-tmpfiles[177]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted90client # [1201389.017424] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...91server # [1201388.372889] server systemd-tmpfiles[177]: fchmod() of /var/log/journal failed: Operation not permitted92client # [1201389.074822] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.93server # [1201388.373009] server systemd-tmpfiles[177]: fchmod() of /var/log/journal/ec245d2b4d77458e929ff7ee9da4be24 failed: Operation not permitted94server # [1201388.373193] server systemd-tmpfiles[177]: fchmod() of /run/log/journal failed: Operation not permitted95server # [1201388.374591] server systemd[1]: Finished Create System Files and Directories.96server # [1201388.375654] server systemd[1]: Starting Rebuild Journal Catalog...97server # [1201388.376422] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...98server # [1201388.389563] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.99server # [1201388.398241] server systemd[1]: Finished Rebuild Journal Catalog.100server # [1201388.399401] server systemd[1]: Starting Update is Completed...101server # [1201388.410143] server systemd[1]: Finished Update is Completed.102server # [1201388.412201] server systemd[1]: Finished Firewall.103server # [1201388.412391] server systemd[1]: Reached target Preparation for Network.104server # [1201388.412646] server systemd[1]: Listening on Network Management Resolve Hook Socket.105server # [1201388.413781] server systemd[1]: Starting Network Management...106server # [1201389.077739] server systemd-networkd[216]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted107server # [1201389.077831] server systemd-networkd[216]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted108server # [1201389.087441] 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.109server # [1201389.087613] 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.110server # [1201389.087780] server systemd-networkd[216]: lo: Link UP111server # [1201389.087783] server systemd-networkd[216]: lo: Gained carrier112server # [1201389.087980] server systemd-networkd[216]: eth1: Configuring with /etc/systemd/network/40-eth1.network.113server # [1201389.088381] server systemd[1]: Started Network Management.114server # [1201389.088464] server systemd-networkd[216]: eth1: Link UP115server # [1201389.088772] server systemd-networkd[216]: eth1: Gained carrier116server # [1201389.089452] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...117server # [1201389.130611] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.118client # [1201389.398842] client systemd-resolved[107]: Positive Trust Anchors:119client # [1201389.398855] client systemd-resolved[107]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d120client # [1201389.398858] client systemd-resolved[107]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16121client # [1201389.398893] client systemd-resolved[107]: 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 test122client # [1201389.421361] client systemd-resolved[107]: Using system hostname 'client'.123client # [1201389.422795] client systemd[1]: Started Network Name Resolution.124client # [1201389.422925] client systemd[1]: Reached target Network.125client # [1201389.423046] client systemd[1]: Reached target System Initialization.126client # [1201389.423139] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container127client # [1201389.423195] client systemd[1]: Started Daily Cleanup of Temporary Directories.128client # [1201389.423236] client systemd[1]: Reached target Timer Units.129client # [1201389.423459] client systemd[1]: Listening on D-Bus System Message Bus Socket.130client # [1201389.423660] client systemd[1]: Listening on Nix Daemon Socket.131client # [1201389.423882] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.132client # [1201389.423929] client systemd[1]: Reached target Socket Units.133client # [1201389.424050] client systemd[1]: Reached target Basic System.134client # [1201389.426664] client systemd[1]: Starting Import lastlog data into lastlog2 database...135client # [1201389.428456] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...136client # [1201389.430720] client systemd[1]: Starting D-Bus System Message Bus...137client # [1201389.488476] client systemd[1]: Finished Import lastlog data into lastlog2 database.138client # [1201389.675133] client systemd[1]: Started Name Service Cache Daemon (nsncd).139client # [1201389.675502] client nsncd[191]: Sep 18 05:10:09.660 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"140client # [1201389.675266] client systemd[1]: Reached target Host and Network Name Lookups.141server # [1201389.461939] server systemd-resolved[152]: Positive Trust Anchors:142server # [1201389.461949] server systemd-resolved[152]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d143server # [1201389.461954] server systemd-resolved[152]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16144server # [1201389.461989] server systemd-resolved[152]: 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 # [1201389.484146] server systemd-resolved[152]: Using system hostname 'server'.146server # [1201389.485662] server systemd[1]: Started Network Name Resolution.147server # [1201389.485807] server systemd[1]: Reached target Network.148server # [1201389.485929] server systemd[1]: Reached target System Initialization.149server # [1201389.486035] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container150server # [1201389.486093] server systemd[1]: Started Daily Cleanup of Temporary Directories.151server # [1201389.486131] server systemd[1]: Reached target Timer Units.152server # [1201389.486367] server systemd[1]: Listening on D-Bus System Message Bus Socket.153server # [1201389.486579] server systemd[1]: Listening on Nix Daemon Socket.154server # [1201389.489549] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).155server # [1201389.491792] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).156server # [1201389.492936] server systemd[1]: Listening on SSH Socket.157server # [1201389.493170] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.158server # [1201389.493225] server systemd[1]: Reached target Socket Units.159server # [1201389.493321] server systemd[1]: Reached target Basic System.160server # [1201389.495059] server systemd[1]: Starting Import lastlog data into lastlog2 database...161server # [1201389.496685] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...162server # [1201389.496735] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.163server # [1201389.499927] server systemd[1]: Starting D-Bus System Message Bus...164server # [1201389.518584] server systemd[1]: Finished Import lastlog data into lastlog2 database.165server # [1201389.665894] server systemd[1]: Started Name Service Cache Daemon (nsncd).166server # [1201389.666469] server nsncd[222]: Sep 18 05:10:09.651 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"167server # [1201389.666028] server systemd[1]: Reached target Host and Network Name Lookups.168server # [1201389.666132] server systemd[1]: Reached target User and Group Name Lookups.169server # [1201389.668967] server systemd[1]: Starting User Login Management...170server # [1201389.670645] server systemd[1]: Starting Permit User Sessions...171server # [1201389.736669] server systemd[1]: Finished Permit User Sessions.172server # [1201389.738379] server systemd[1]: Started Console Getty.173server # [1201389.738454] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0174server # [1201389.738493] server systemd[1]: Reached target Login Prompts.175server # [1201389.831098] server dbus-broker-launch[223]: Looking up NSS user entry for 'systemd-timesync'...176server # [1201389.835211] server dbus-broker-launch[223]: NSS returned no entry for 'systemd-timesync'177server # [1201389.835211] server dbus-broker-launch[223]: Invalid user-name in /nix/store/mywsj5ag7l5gg0yiywr8lw7plq62w0wp-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"178server # [1201389.833151] server systemd[1]: Started D-Bus System Message Bus.179server # [1201389.841582] server dbus-broker-launch[223]: Ready180client # [1201389.675376] client systemd[1]: Reached target User and Group Name Lookups.181client # [1201389.728872] client systemd[1]: Starting User Login Management...182client # [1201389.730135] client systemd[1]: Starting Permit User Sessions...183client # [1201389.739822] client systemd[1]: Finished Permit User Sessions.184client # [1201389.741471] client systemd[1]: Started Console Getty.185client # [1201389.741549] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0186client # [1201389.741593] client systemd[1]: Reached target Login Prompts.187client # [1201389.835289] client dbus-broker-launch[192]: Looking up NSS user entry for 'systemd-timesync'...188client # [1201389.837209] client dbus-broker-launch[192]: NSS returned no entry for 'systemd-timesync'189client # [1201389.837209] client dbus-broker-launch[192]: Invalid user-name in /nix/store/m9fnixjdwykjbw4243q60hm3kkwxkq89-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"190client # [1201389.837665] client systemd[1]: Started D-Bus System Message Bus.191client # [1201389.846386] client dbus-broker-launch[192]: Ready192server # [1201390.243561] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.193client # [1201390.240616] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.194server # [1201390.245336] server systemd[1]: Finished Save Transient machine-id to Disk.195client # [1201390.242992] client systemd[1]: Finished Save Transient machine-id to Disk.196server # [1201390.515455] server systemd-logind[238]: New seat seat0.197server # [1201390.515702] server systemd[1]: Started User Login Management.198server # [1201390.576760] server systemd[1]: Starting linger-users.service...199server # [1201390.591725] server systemd[1]: linger-users.service: Deactivated successfully.200server # [1201390.591928] server systemd[1]: Finished linger-users.service.201server # [1201390.592361] server systemd-networkd[216]: eth1: Gained IPv6LL202server # [1201390.592487] server systemd[1]: Reached target Multi-User System.203server # [1201390.592686] server systemd[1]: Startup finished in 2.763s.204client # [1201390.511232] client systemd-logind[207]: New seat seat0.205client # [1201390.511473] client systemd[1]: Started User Login Management.206client # [1201390.513490] client systemd[1]: Starting linger-users.service...207client # [1201390.586238] client systemd[1]: linger-users.service: Deactivated successfully.208client # [1201390.586432] client systemd[1]: Finished linger-users.service.209client # [1201390.587063] client systemd[1]: Reached target Multi-User System.210client # [1201390.587366] client systemd[1]: Startup finished in 2.827s.211client: waiting for unit network-online.target212client: (finished: waiting for unit network-online.target, in 0.03 seconds)213server: waiting for unit network-online.target214server: (finished: waiting for unit network-online.target, in 0.02 seconds)215client: 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/null216server # [1201390.915388] server systemd[1]: Reached target Network is Online.217client # [1201390.898847] client systemd[1]: Reached target Network is Online.218client # [1201391.040130] client systemd-networkd[178]: eth1: Gained IPv6LL219client: (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 1.14 seconds)220(finished: run the VM test script, in 4.86 seconds)221server # [1201392.008600] server systemd[1]: Created slice Slice /system/sshd.222server # [1201392.008698] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.223server # [1201392.009844] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:59260).224server # [1201392.097611] server sshd-session[255]: Accepted publickey for root from 2001:db8:1::1 port 59260 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ225server # [1201392.110797] server sshd-session[259]: Received disconnect from 2001:db8:1::1 port 59260:11: disconnected by user226server # [1201392.111012] server sshd-session[259]: Disconnected from user root 2001:db8:1::1 port 59260227server # [1201392.112389] server systemd[1]: sshd@0-253959-2001:db8:1::2:22-2001:db8:1::1:59260.service: Deactivated successfully.228test script finished in 5.13s229cleanup230kill NspawnMachine (pid 52)231kill NspawnMachine (pid 53)232Container client terminated by signal KILL.233(finished: cleanup, in 0.28 seconds)234Container server terminated by signal KILL.