container-test-run-admin
checks.aarch64-linux.admin
· build #513
· 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.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.25server # No journal files were found.26server # No journal boot entry found for the specified boot (+0).27client # No journal files were found.28client # No journal boot entry found for the specified boot (+0).29server # [6900342.767261] server systemd-journald[97]: Journal started30client # [6900342.770369] client systemd-journald[69]: Journal started31server # [6900342.767319] server systemd-journald[97]: Runtime Journal (/run/log/journal/3d086767ef7b4482bcf7b02dfffbdce8) is 8M, max 2.5G, 2.4G free.32client # [6900342.770424] client systemd-journald[69]: Runtime Journal (/run/log/journal/1d027c5389db4b7688b6aaeed7bd926b) is 8M, max 2.5G, 2.4G free.33server # [6900342.769433] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.34client # [6900342.774142] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.35server # [6900342.777412] server systemd[1]: Starting Flush Journal to Persistent Storage...36client # [6900342.784980] client systemd[1]: Starting Flush Journal to Persistent Storage...37server # [6900342.778235] server systemd[1]: Starting Network Name Resolution...38client # [6900342.786350] client systemd[1]: Starting Network Name Resolution...39server # [6900342.778895] server systemd[1]: Starting Create Static Device Nodes in /dev...40client # [6900342.787195] client systemd[1]: Starting Create Static Device Nodes in /dev...41server # [6900342.788047] server systemd-journald[97]: Time spent on flushing to /var/log/journal/3d086767ef7b4482bcf7b02dfffbdce8 is 1.481ms for 6 entries.42client # [6900342.795835] client systemd-journald[69]: Time spent on flushing to /var/log/journal/1d027c5389db4b7688b6aaeed7bd926b is 1.492ms for 6 entries.43server # [6900342.788047] server systemd-journald[97]: System Journal (/var/log/journal/3d086767ef7b4482bcf7b02dfffbdce8) is 8M, max 4G, 3.9G free.44client # [6900342.795835] client systemd-journald[69]: System Journal (/var/log/journal/1d027c5389db4b7688b6aaeed7bd926b) is 8M, max 4G, 3.9G free.45server # [6900342.794736] server systemd[1]: Finished Create Static Device Nodes in /dev.46client # [6900342.803500] client systemd[1]: Finished Create Static Device Nodes in /dev.47server # [6900342.794978] server systemd[1]: Reached target Preparation for Local File Systems.48client # [6900342.803745] client systemd[1]: Reached target Preparation for Local File Systems.49server # [6900342.795064] server systemd[1]: Reached target Local File Systems.50client # [6900342.803832] client systemd[1]: Reached target Local File Systems.51server # [6900342.795791] server systemd[1]: Listening on Boot Loader Control Service Socket.52client # [6900342.804582] client systemd[1]: Listening on Boot Loader Control Service Socket.53server # [6900342.795835] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container54client # [6900342.804633] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container55server # [6900342.796671] server systemd[1]: Starting Save Transient machine-id to Disk...56client # [6900342.805482] client systemd[1]: Starting Save Transient machine-id to Disk...57server # [6900342.796704] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys58client # [6900342.805517] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys59server # [6900342.809088] server systemd[1]: Finished Flush Journal to Persistent Storage.60client # [6900342.812467] client systemd[1]: Finished Flush Journal to Persistent Storage.61server # [6900342.810553] server systemd[1]: Starting Create System Files and Directories...62client # [6900342.814092] client systemd[1]: Starting Create System Files and Directories...63server # [6900342.826741] server systemd-tmpfiles[143]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted64client # [6900342.828245] client systemd-tmpfiles[116]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted65server # [6900342.826910] server systemd-tmpfiles[143]: fchmod() of /var/log/journal failed: Operation not permitted66client # [6900342.828416] client systemd-tmpfiles[116]: fchmod() of /var/log/journal failed: Operation not permitted67server # [6900342.827026] server systemd-tmpfiles[143]: fchmod() of /var/log/journal/3d086767ef7b4482bcf7b02dfffbdce8 failed: Operation not permitted68client # [6900342.828537] client systemd-tmpfiles[116]: fchmod() of /var/log/journal/1d027c5389db4b7688b6aaeed7bd926b failed: Operation not permitted69server # [6900342.827199] server systemd-tmpfiles[143]: fchmod() of /run/log/journal failed: Operation not permitted70client # [6900342.828717] client systemd-tmpfiles[116]: fchmod() of /run/log/journal failed: Operation not permitted71server # [6900342.828649] server systemd[1]: Finished Create System Files and Directories.72client # [6900342.830305] client systemd[1]: Finished Create System Files and Directories.73server # [6900342.829998] server systemd[1]: Starting Rebuild Journal Catalog...74client # [6900342.831320] client systemd[1]: Starting Rebuild Journal Catalog...75server # [6900342.830991] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...76client # [6900342.832038] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...77server # [6900342.845237] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.78client # [6900342.846457] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.79server # [6900342.851670] server systemd[1]: Finished Rebuild Journal Catalog.80client # [6900342.852284] client systemd[1]: Finished Rebuild Journal Catalog.81server # [6900342.852788] server systemd[1]: Starting Update is Completed...82client # [6900342.853596] client systemd[1]: Starting Update is Completed...83server # [6900342.863852] server systemd[1]: Finished Update is Completed.84client # [6900342.867361] client systemd[1]: Finished Update is Completed.85server # [6900342.877934] server systemd[1]: Finished Save Transient machine-id to Disk.86client # [6900342.877465] client systemd[1]: Finished Save Transient machine-id to Disk.87server # [6900342.927843] server systemd[1]: Finished Firewall.88client # [6900342.925921] client systemd[1]: Finished Firewall.89server # [6900342.928027] server systemd[1]: Reached target Preparation for Network.90client # [6900342.926063] client systemd[1]: Reached target Preparation for Network.91server # [6900342.928271] server systemd[1]: Listening on Network Management Resolve Hook Socket.92client # [6900342.926279] client systemd[1]: Listening on Network Management Resolve Hook Socket.93server # [6900342.929478] server systemd[1]: Starting Network Management...94client # [6900342.927343] client systemd[1]: Starting Network Management...95server # [6900343.300916] server systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted96client # [6900343.306233] client systemd-networkd[183]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted97server # [6900343.301006] server systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted98client # [6900343.306325] client systemd-networkd[183]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted99server # [6900343.307924] server systemd-networkd[213]: /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.100client # [6900343.313246] client systemd-networkd[183]: /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.101server # [6900343.308108] server systemd-networkd[213]: /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.102client # [6900343.313414] client systemd-networkd[183]: /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.103server # [6900343.308293] server systemd-networkd[213]: lo: Link UP104client # [6900343.313582] client systemd-networkd[183]: lo: Link UP105server # [6900343.308297] server systemd-networkd[213]: lo: Gained carrier106client # [6900343.313586] client systemd-networkd[183]: lo: Gained carrier107server # [6900343.308475] server systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.108client # [6900343.313761] client systemd-networkd[183]: eth1: Configuring with /etc/systemd/network/40-eth1.network.109server # [6900343.308971] server systemd[1]: Started Network Management.110client # [6900343.314187] client systemd[1]: Started Network Management.111server # [6900343.309234] server systemd-networkd[213]: eth1: Link UP112client # [6900343.314247] client systemd-networkd[183]: eth1: Link UP113server # [6900343.309421] server systemd-networkd[213]: eth1: Gained carrier114client # [6900343.314513] client systemd-networkd[183]: eth1: Gained carrier115server # [6900343.311036] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...116client # [6900343.316253] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...117server # [6900343.434073] server systemd-resolved[119]: Positive Trust Anchors:118client # [6900343.448300] client systemd-resolved[95]: Positive Trust Anchors:119server # [6900343.434085] server systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d120client # [6900343.448313] client systemd-resolved[95]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d121server # [6900343.434088] server systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16122client # [6900343.448317] client systemd-resolved[95]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16123server # [6900343.434123] server systemd-resolved[119]: 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 test124client # [6900343.448352] client systemd-resolved[95]: 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 test125server # [6900343.457028] server systemd-resolved[119]: Using system hostname 'server'.126client # [6900343.471093] client systemd-resolved[95]: Using system hostname 'client'.127server # [6900343.458507] server systemd[1]: Started Network Name Resolution.128client # [6900343.472540] client systemd[1]: Started Network Name Resolution.129server # [6900343.458654] server systemd[1]: Reached target Network.130client # [6900343.472670] client systemd[1]: Reached target Network.131server # [6900343.458779] server systemd[1]: Reached target System Initialization.132client # [6900343.472795] client systemd[1]: Reached target System Initialization.133server # [6900343.458877] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container134client # [6900343.472892] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container135server # [6900343.458931] server systemd[1]: Started Daily Cleanup of Temporary Directories.136client # [6900343.472949] client systemd[1]: Started Daily Cleanup of Temporary Directories.137server # [6900343.458971] server systemd[1]: Reached target Timer Units.138client # [6900343.472986] client systemd[1]: Reached target Timer Units.139server # [6900343.459200] server systemd[1]: Listening on D-Bus System Message Bus Socket.140client # [6900343.473207] client systemd[1]: Listening on D-Bus System Message Bus Socket.141server # [6900343.459424] server systemd[1]: Listening on Nix Daemon Socket.142client # [6900343.473422] client systemd[1]: Listening on Nix Daemon Socket.143server # [6900343.461889] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).144client # [6900343.473646] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.145server # [6900343.464194] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).146client # [6900343.473697] client systemd[1]: Reached target Socket Units.147server # [6900343.465374] server systemd[1]: Listening on SSH Socket.148client # [6900343.473772] client systemd[1]: Reached target Basic System.149server # [6900343.465607] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.150client # [6900343.545508] client systemd[1]: Starting Import lastlog data into lastlog2 database...151server # [6900343.465664] server systemd[1]: Reached target Socket Units.152client # [6900343.547567] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...153server # [6900343.465767] server systemd[1]: Reached target Basic System.154client # [6900343.550107] client systemd[1]: Starting D-Bus System Message Bus...155server # [6900343.545733] server systemd[1]: Starting Import lastlog data into lastlog2 database...156client # [6900343.556714] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.157server # [6900343.547407] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...158client # [6900343.568713] client systemd[1]: Finished Import lastlog data into lastlog2 database.159server # [6900343.547472] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.160server # [6900343.551114] server systemd[1]: Starting D-Bus System Message Bus...161server # [6900343.555727] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.162server # [6900343.570106] server systemd[1]: Finished Import lastlog data into lastlog2 database.163client # [6900343.665665] client systemd[1]: Started Name Service Cache Daemon (nsncd).164client # [6900343.665783] client systemd[1]: Reached target Host and Network Name Lookups.165client # [6900343.665971] client nsncd[188]: Aug 27 20:09:29.718 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"166client # [6900343.665895] client systemd[1]: Reached target User and Group Name Lookups.167client # [6900343.668069] client systemd[1]: Starting User Login Management...168client # [6900343.669289] client systemd[1]: Starting Permit User Sessions...169client # [6900343.710472] client systemd[1]: Finished Permit User Sessions.170client # [6900343.711421] client systemd[1]: Started Console Getty.171client # [6900343.711458] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0172client # [6900343.711475] client systemd[1]: Reached target Login Prompts.173client # [6900343.743149] client dbus-broker-launch[189]: Looking up NSS user entry for 'systemd-timesync'...174client # [6900343.744455] client dbus-broker-launch[189]: NSS returned no entry for 'systemd-timesync'175client # [6900343.744455] client dbus-broker-launch[189]: Invalid user-name in /nix/store/4vgxxm5pm041xfw8cndff8xyj1921rvv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"176client # [6900343.745118] client systemd[1]: Started D-Bus System Message Bus.177client # [6900343.753300] client dbus-broker-launch[189]: Ready178client # [6900343.764291] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.179server # [6900343.665768] server systemd[1]: Started Name Service Cache Daemon (nsncd).180server # [6900343.665873] server systemd[1]: Reached target Host and Network Name Lookups.181server # [6900343.665980] server systemd[1]: Reached target User and Group Name Lookups.182server # [6900343.666332] server nsncd[218]: Aug 27 20:09:29.719 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"183server # [6900343.668293] server systemd[1]: Starting User Login Management...184server # [6900343.669410] server systemd[1]: Starting Permit User Sessions...185server # [6900343.710514] server systemd[1]: Finished Permit User Sessions.186server # [6900343.711605] server systemd[1]: Started Console Getty.187server # [6900343.711654] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0188server # [6900343.711673] server systemd[1]: Reached target Login Prompts.189server # [6900343.743022] server dbus-broker-launch[219]: Looking up NSS user entry for 'systemd-timesync'...190server # [6900343.744436] server dbus-broker-launch[219]: NSS returned no entry for 'systemd-timesync'191server # [6900343.744436] server dbus-broker-launch[219]: Invalid user-name in /nix/store/52xsxm35gzjg9xl763bq7c267k12yy3g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"192server # [6900343.744788] server systemd[1]: Started D-Bus System Message Bus.193server # [6900343.753618] server dbus-broker-launch[219]: Ready194server # [6900343.757224] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.195client # [6900344.136510] client systemd-logind[205]: New seat seat0.196client # [6900344.136672] client systemd[1]: Started User Login Management.197client # [6900344.356598] client systemd[1]: Starting linger-users.service...198client # [6900344.369982] client systemd[1]: linger-users.service: Deactivated successfully.199client # [6900344.370097] client systemd[1]: Finished linger-users.service.200client # [6900344.370592] client systemd[1]: Reached target Multi-User System.201client # [6900344.404097] client systemd[1]: Startup finished in 2.273s.202server # [6900344.127725] server systemd-logind[235]: New seat seat0.203server # [6900344.127955] server systemd[1]: Started User Login Management.204server # [6900344.130345] server systemd[1]: Starting linger-users.service...205server # [6900344.367923] server systemd[1]: linger-users.service: Deactivated successfully.206server # [6900344.368212] server systemd[1]: Finished linger-users.service.207server # [6900344.369071] server systemd[1]: Reached target Multi-User System.208server # [6900344.369473] server systemd[1]: Startup finished in 2.188s.209client # [6900344.774754] client systemd[1]: Reached target Network is Online.210client: waiting for unit network-online.target211client: (finished: waiting for unit network-online.target, in 0.03 seconds)212server: waiting for unit network-online.target213server: (finished: waiting for unit network-online.target, in 0.02 seconds)214client: 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/null215client: (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.19 seconds)216(finished: run the VM test script, in 3.41 seconds)217server # [6900344.791914] server systemd[1]: Reached target Network is Online.218server # [6900344.867247] server systemd[1]: Created slice Slice /system/sshd.219server # [6900344.867399] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.220server # [6900344.869092] server systemd[1]: Started SSH per-connection Daemon (192.168.1.1:56296).221server # [6900345.016659] server sshd-session[251]: Accepted publickey for root from 192.168.1.1 port 56296 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ222server # [6900345.031677] server sshd-session[255]: Received disconnect from 192.168.1.1 port 56296:11: disconnected by user223server # [6900345.031872] server sshd-session[255]: Disconnected from user root 192.168.1.1 port 56296224server # [6900345.033464] server systemd[1]: sshd@0-245822-192.168.1.2:22-192.168.1.1:56296.service: Deactivated successfully.225test script finished in 3.50s226cleanup227kill NspawnMachine (pid 52)228kill NspawnMachine (pid 53)229Container client terminated by signal KILL.230Container server terminated by signal KILL.231(finished: cleanup, in 0.38 seconds)