container-test-run-admin
checks.aarch64-linux.admin
· build #568
· 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 53)13server: systemd-nspawn running (pid 52)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.25server # [7451177.111509] server systemd-journald[98]: Journal started26server # [7451177.111561] server systemd-journald[98]: Runtime Journal (/run/log/journal/b2ce153dcd4d47c29724ab6f4a1eee8b) is 8M, max 2.5G, 2.4G free.27server # [7451177.118256] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.28server # [7451177.131879] server systemd[1]: Starting Flush Journal to Persistent Storage...29server # [7451177.132699] server systemd[1]: Starting Network Name Resolution...30server # [7451177.133372] server systemd[1]: Starting Create Static Device Nodes in /dev...31server # [7451177.140775] server systemd-journald[98]: Time spent on flushing to /var/log/journal/b2ce153dcd4d47c29724ab6f4a1eee8b is 1.748ms for 6 entries.32server # [7451177.140775] server systemd-journald[98]: System Journal (/var/log/journal/b2ce153dcd4d47c29724ab6f4a1eee8b) is 8M, max 4G, 3.9G free.33server # [7451177.144444] server systemd[1]: Finished Create Static Device Nodes in /dev.34server # [7451177.145139] server systemd[1]: Reached target Preparation for Local File Systems.35server # [7451177.145276] server systemd[1]: Reached target Local File Systems.36server # [7451177.146106] server systemd[1]: Listening on Boot Loader Control Service Socket.37server # [7451177.146149] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container38server # [7451177.147032] server systemd[1]: Starting Save Transient machine-id to Disk...39server # [7451177.147066] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys40server # [7451177.167204] server systemd[1]: Finished Flush Journal to Persistent Storage.41server # [7451177.168792] server systemd[1]: Starting Create System Files and Directories...42client # [7451176.924936] client systemd-journald[69]: Journal started43client # [7451176.925012] client systemd-journald[69]: Runtime Journal (/run/log/journal/67f13407be0d4bb8a1cc744158b6ae9b) is 8M, max 2.5G, 2.4G free.44client # [7451176.926308] client systemd[1]: Finished Apply Kernel Variables.45client # [7451176.935808] client systemd[1]: Finished Create Static Device Nodes in /dev gracefully.46client # [7451176.944824] client systemd[1]: Starting Flush Journal to Persistent Storage...47client # [7451176.945704] client systemd[1]: Starting Network Name Resolution...48client # [7451176.946450] client systemd[1]: Starting Create Static Device Nodes in /dev...49client # [7451176.953757] client systemd-journald[69]: Time spent on flushing to /var/log/journal/67f13407be0d4bb8a1cc744158b6ae9b is 1.489ms for 7 entries.50client # [7451176.953757] client systemd-journald[69]: System Journal (/var/log/journal/67f13407be0d4bb8a1cc744158b6ae9b) is 8M, max 4G, 3.9G free.51client # [7451176.959893] client systemd[1]: Finished Create Static Device Nodes in /dev.52client # [7451176.960170] client systemd[1]: Reached target Preparation for Local File Systems.53client # [7451176.960255] client systemd[1]: Reached target Local File Systems.54client # [7451176.961000] client systemd[1]: Listening on Boot Loader Control Service Socket.55client # [7451176.961043] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container56client # [7451176.961885] client systemd[1]: Starting Save Transient machine-id to Disk...57client # [7451176.961922] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys58client # [7451176.979005] client systemd[1]: Finished Flush Journal to Persistent Storage.59client # [7451176.980501] client systemd[1]: Starting Create System Files and Directories...60client # [7451176.996323] client systemd-tmpfiles[127]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted61client # [7451176.996744] client systemd-tmpfiles[127]: fchmod() of /var/log/journal failed: Operation not permitted62client # [7451176.996881] client systemd-tmpfiles[127]: fchmod() of /var/log/journal/67f13407be0d4bb8a1cc744158b6ae9b failed: Operation not permitted63client # [7451176.997099] client systemd-tmpfiles[127]: fchmod() of /run/log/journal failed: Operation not permitted64client # [7451176.999837] client systemd[1]: Finished Create System Files and Directories.65client # [7451177.001121] client systemd[1]: Starting Rebuild Journal Catalog...66client # [7451177.001930] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...67client # [7451177.004768] client systemd[1]: Finished Save Transient machine-id to Disk.68client # [7451177.013054] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.69client # [7451177.020903] client systemd[1]: Finished Rebuild Journal Catalog.70client # [7451177.021969] client systemd[1]: Starting Update is Completed...71client # [7451177.034609] client systemd[1]: Finished Update is Completed.72client # [7451177.061924] client systemd[1]: Finished Firewall.73client # [7451177.062085] client systemd[1]: Reached target Preparation for Network.74client # [7451177.062307] client systemd[1]: Listening on Network Management Resolve Hook Socket.75client # [7451177.063451] client systemd[1]: Starting Network Management...76server # [7451177.184206] server systemd-tmpfiles[157]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted77server # [7451177.184393] server systemd-tmpfiles[157]: fchmod() of /var/log/journal failed: Operation not permitted78server # [7451177.184513] server systemd-tmpfiles[157]: fchmod() of /var/log/journal/b2ce153dcd4d47c29724ab6f4a1eee8b failed: Operation not permitted79server # [7451177.184692] server systemd-tmpfiles[157]: fchmod() of /run/log/journal failed: Operation not permitted80server # [7451177.185630] server systemd[1]: Finished Create System Files and Directories.81server # [7451177.187863] server systemd[1]: Starting Rebuild Journal Catalog...82server # [7451177.188842] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...83server # [7451177.190227] server systemd[1]: Finished Save Transient machine-id to Disk.84server # [7451177.200485] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.85server # [7451177.207563] server systemd[1]: Finished Rebuild Journal Catalog.86server # [7451177.208651] server systemd[1]: Starting Update is Completed...87server # [7451177.218671] server systemd[1]: Finished Update is Completed.88server # [7451177.248110] server systemd[1]: Finished Firewall.89server # [7451177.248271] server systemd[1]: Reached target Preparation for Network.90server # [7451177.248485] server systemd[1]: Listening on Network Management Resolve Hook Socket.91server # [7451177.249497] server systemd[1]: Starting Network Management...92client # [7451177.565512] client systemd-networkd[183]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted93client # [7451177.565605] client systemd-networkd[183]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted94client # [7451177.572937] 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.95client # [7451177.573101] 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.96client # [7451177.573270] client systemd-networkd[183]: lo: Link UP97client # [7451177.573279] client systemd-networkd[183]: lo: Gained carrier98client # [7451177.573471] client systemd-networkd[183]: eth1: Configuring with /etc/systemd/network/40-eth1.network.99client # [7451177.573903] client systemd[1]: Started Network Management.100client # [7451177.574201] client systemd-networkd[183]: eth1: Link UP101client # [7451177.574756] client systemd-networkd[183]: eth1: Gained carrier102client # [7451177.575274] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...103client # [7451177.614230] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.104client # [7451177.841637] client systemd-resolved[99]: Positive Trust Anchors:105client # [7451177.841651] client systemd-resolved[99]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d106client # [7451177.841654] client systemd-resolved[99]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16107client # [7451177.841688] client systemd-resolved[99]: 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 test108server # [7451177.745244] server systemd-networkd[214]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted109server # [7451177.745338] server systemd-networkd[214]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted110server # [7451177.751915] server systemd-networkd[214]: /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.111server # [7451177.752111] server systemd-networkd[214]: /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.112server # [7451177.752284] server systemd-networkd[214]: lo: Link UP113server # [7451177.752289] server systemd-networkd[214]: lo: Gained carrier114server # [7451177.752497] server systemd-networkd[214]: eth1: Configuring with /etc/systemd/network/40-eth1.network.115server # [7451177.752850] server systemd[1]: Started Network Management.116server # [7451177.752956] server systemd-networkd[214]: eth1: Link UP117server # [7451177.753182] server systemd-networkd[214]: eth1: Gained carrier118server # [7451177.754257] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...119server # [7451177.808135] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.120server # [7451178.040470] server systemd-resolved[129]: Positive Trust Anchors:121server # [7451178.040483] server systemd-resolved[129]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d122server # [7451178.040486] server systemd-resolved[129]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16123client # [7451177.864887] client systemd-resolved[99]: Using system hostname 'client'.124client # [7451177.866262] client systemd[1]: Started Network Name Resolution.125client # [7451177.866343] client systemd[1]: Reached target Network.126client # [7451177.866404] client systemd[1]: Reached target System Initialization.127client # [7451177.866451] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container128client # [7451177.866479] client systemd[1]: Started Daily Cleanup of Temporary Directories.129client # [7451177.866496] client systemd[1]: Reached target Timer Units.130client # [7451177.866614] client systemd[1]: Listening on D-Bus System Message Bus Socket.131client # [7451177.866838] client systemd[1]: Listening on Nix Daemon Socket.132client # [7451177.866951] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.133client # [7451177.866972] client systemd[1]: Reached target Socket Units.134client # [7451177.867013] client systemd[1]: Reached target Basic System.135client # [7451177.868183] client systemd[1]: Starting Import lastlog data into lastlog2 database...136client # [7451177.869001] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...137client # [7451177.870723] client systemd[1]: Starting D-Bus System Message Bus...138client # [7451177.886591] client systemd[1]: Finished Import lastlog data into lastlog2 database.139client # [7451177.916646] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.140client # [7451177.994650] client nsncd[189]: Sep 03 05:10:04.047 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"141client # [7451177.994757] client systemd[1]: Started Name Service Cache Daemon (nsncd).142client # [7451177.994819] client systemd[1]: Reached target Host and Network Name Lookups.143client # [7451177.994880] client systemd[1]: Reached target User and Group Name Lookups.144client # [7451177.996311] client systemd[1]: Starting User Login Management...145client # [7451177.997123] client systemd[1]: Starting Permit User Sessions...146client # [7451178.006408] client systemd[1]: Finished Permit User Sessions.147client # [7451178.007617] client systemd[1]: Started Console Getty.148client # [7451178.007659] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0149client # [7451178.007677] client systemd[1]: Reached target Login Prompts.150client # [7451178.118187] client dbus-broker-launch[190]: Looking up NSS user entry for 'systemd-timesync'...151client # [7451178.118781] client dbus-broker-launch[190]: NSS returned no entry for 'systemd-timesync'152client # [7451178.118781] client dbus-broker-launch[190]: Invalid user-name in /nix/store/4vgxxm5pm041xfw8cndff8xyj1921rvv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"153server # [7451178.040523] server systemd-resolved[129]: 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 test154server # [7451178.063372] server systemd-resolved[129]: Using system hostname 'server'.155server # [7451178.064853] server systemd[1]: Started Network Name Resolution.156server # [7451178.064942] server systemd[1]: Reached target Network.157server # [7451178.065008] server systemd[1]: Reached target System Initialization.158server # [7451178.065063] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container159server # [7451178.065092] server systemd[1]: Started Daily Cleanup of Temporary Directories.160server # [7451178.065109] server systemd[1]: Reached target Timer Units.161server # [7451178.065234] server systemd[1]: Listening on D-Bus System Message Bus Socket.162server # [7451178.065355] server systemd[1]: Listening on Nix Daemon Socket.163server # [7451178.066336] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).164server # [7451178.067170] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).165server # [7451178.067600] server systemd[1]: Listening on SSH Socket.166server # [7451178.067706] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.167server # [7451178.067730] server systemd[1]: Reached target Socket Units.168server # [7451178.067770] server systemd[1]: Reached target Basic System.169server # [7451178.068942] server systemd[1]: Starting Import lastlog data into lastlog2 database...170server # [7451178.069861] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...171server # [7451178.069892] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.172server # [7451178.071493] server systemd[1]: Starting D-Bus System Message Bus...173server # [7451178.086871] server systemd[1]: Finished Import lastlog data into lastlog2 database.174server # [7451178.108910] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.175client # [7451178.119168] client systemd[1]: Started D-Bus System Message Bus.176client # [7451178.126206] client dbus-broker-launch[190]: Ready177server # [7451178.382757] server nsncd[220]: Sep 03 05:10:04.435 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"178server # [7451178.382961] server systemd[1]: Started Name Service Cache Daemon (nsncd).179server # [7451178.383066] server systemd[1]: Reached target Host and Network Name Lookups.180server # [7451178.383173] server systemd[1]: Reached target User and Group Name Lookups.181server # [7451178.386732] server systemd[1]: Starting User Login Management...182server # [7451178.388041] server systemd[1]: Starting Permit User Sessions...183server # [7451178.397685] server systemd[1]: Finished Permit User Sessions.184server # [7451178.398862] server systemd[1]: Started Console Getty.185server # [7451178.398898] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0186server # [7451178.398915] server systemd[1]: Reached target Login Prompts.187server # [7451178.621398] server dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...188server # [7451178.622157] server dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'189server # [7451178.622157] server dbus-broker-launch[221]: Invalid user-name in /nix/store/52xsxm35gzjg9xl763bq7c267k12yy3g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"190server # [7451178.622531] server systemd[1]: Started D-Bus System Message Bus.191server # [7451178.629549] server dbus-broker-launch[221]: Ready192client # [7451178.993677] client systemd-logind[205]: New seat seat0.193client # [7451178.993871] client systemd[1]: Started User Login Management.194client # [7451179.017413] client systemd[1]: Starting linger-users.service...195client # [7451179.030024] client systemd[1]: linger-users.service: Deactivated successfully.196client # [7451179.030258] client systemd[1]: Finished linger-users.service.197client # [7451179.030669] client systemd[1]: Reached target Multi-User System.198client # [7451179.036150] client systemd[1]: Startup finished in 2.430s.199client: waiting for unit network-online.target200client: (finished: waiting for unit network-online.target, in 0.02 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: (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.11 seconds)205(finished: run the VM test script, in 3.37 seconds)206test script finished in 3.42s207cleanup208kill NspawnMachine (pid 53)209client # [7451179.307464] client systemd[1]: Reached target Network is Online.210client # [7451179.524181] client systemd-networkd[183]: eth1: Gained IPv6LL211client # [7451179.587079] client systemd-networkd[183]: eth1: Link DOWN212client # [7451179.587095] client systemd-networkd[183]: eth1: Lost carrier213server # [7451179.296302] server systemd-networkd[214]: eth1: Gained IPv6LL214server # [7451179.325145] server systemd-logind[236]: New seat seat0.215server # [7451179.325331] server systemd[1]: Started User Login Management.216server # [7451179.326539] server systemd[1]: Starting linger-users.service...217server # [7451179.342046] server systemd[1]: linger-users.service: Deactivated successfully.218server # [7451179.342239] server systemd[1]: Finished linger-users.service.219server # [7451179.342625] server systemd[1]: Reached target Multi-User System.220server # [7451179.342777] server systemd[1]: Startup finished in 2.659s.221server # [7451179.388806] server systemd[1]: Reached target Network is Online.222server # [7451179.429997] server systemd[1]: Created slice Slice /system/sshd.223server # [7451179.430070] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.224server # [7451179.431139] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:55302).225server # [7451179.514568] server sshd-session[252]: Accepted publickey for root from 2001:db8:1::1 port 55302 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ226server # [7451179.529228] server sshd-session[256]: Received disconnect from 2001:db8:1::1 port 55302:11: disconnected by user227server # [7451179.529407] server sshd-session[256]: Disconnected from user root 2001:db8:1::1 port 55302228server # [7451179.530523] server systemd[1]: sshd@0-32839-2001:db8:1::2:22-2001:db8:1::1:55302.service: Deactivated successfully.229kill NspawnMachine (pid 52)230Container client terminated by signal KILL.231Container server terminated by signal KILL.232(finished: cleanup, in 0.38 seconds)