container-test-run-admin
checks.aarch64-linux.admin
· build #451
· 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(server): TAP vde-tap1 not found; container will be isolated from VDE18nixos-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.19nixos-nspawn(client): TAP vde-tap1 not found; container will be isolated from VDE20nixos-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.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 server on /build/vm-state-server.24░ Spawning container client on /build/vm-state-client.25server # [6334046.058372] server systemd-journald[98]: Journal started26server # [6334046.058431] server systemd-journald[98]: Runtime Journal (/run/log/journal/fa3e7a62828345e283acaf0565bea053) is 8M, max 2.5G, 2.4G free.27server # [6334046.063019] server systemd[1]: Starting Flush Journal to Persistent Storage...28server # [6334046.063899] server systemd[1]: Starting Network Name Resolution...29server # [6334046.064604] server systemd[1]: Starting Create Static Device Nodes in /dev...30server # [6334046.074423] server systemd-journald[98]: Time spent on flushing to /var/log/journal/fa3e7a62828345e283acaf0565bea053 is 1.619ms for 5 entries.31server # [6334046.074423] server systemd-journald[98]: System Journal (/var/log/journal/fa3e7a62828345e283acaf0565bea053) is 8M, max 4G, 3.9G free.32server # [6334046.078973] server systemd[1]: Finished Create Static Device Nodes in /dev.33server # [6334046.079217] server systemd[1]: Reached target Preparation for Local File Systems.34server # [6334046.079299] server systemd[1]: Reached target Local File Systems.35server # [6334046.080052] server systemd[1]: Listening on Boot Loader Control Service Socket.36server # [6334046.080097] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container37server # [6334046.080913] server systemd[1]: Starting Save Transient machine-id to Disk...38server # [6334046.080946] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys39server # [6334046.091910] server systemd[1]: Finished Flush Journal to Persistent Storage.40server # [6334046.093393] server systemd[1]: Starting Create System Files and Directories...41server # [6334046.107482] server systemd-tmpfiles[142]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted42server # [6334046.107646] server systemd-tmpfiles[142]: fchmod() of /var/log/journal failed: Operation not permitted43server # [6334046.107759] server systemd-tmpfiles[142]: fchmod() of /var/log/journal/fa3e7a62828345e283acaf0565bea053 failed: Operation not permitted44server # [6334046.107927] server systemd-tmpfiles[142]: fchmod() of /run/log/journal failed: Operation not permitted45server # [6334046.109354] server systemd[1]: Finished Create System Files and Directories.46server # [6334046.110400] server systemd[1]: Starting Rebuild Journal Catalog...47server # [6334046.111169] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...48server # [6334046.123618] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.49server # [6334046.129046] server systemd[1]: Finished Rebuild Journal Catalog.50server # [6334046.130185] server systemd[1]: Starting Update is Completed...51server # [6334046.142056] server systemd[1]: Finished Update is Completed.52client # [6334046.039128] client systemd-journald[69]: Journal started53client # [6334046.039190] client systemd-journald[69]: Runtime Journal (/run/log/journal/2a5a4dc7e40b4b05b63c0d39cc3a2f27) is 8M, max 2.5G, 2.4G free.54client # [6334046.042842] client systemd[1]: Starting Flush Journal to Persistent Storage...55client # [6334046.043627] client systemd[1]: Starting Network Name Resolution...56client # [6334046.044257] client systemd[1]: Starting Create Static Device Nodes in /dev...57client # [6334046.053112] client systemd-journald[69]: Time spent on flushing to /var/log/journal/2a5a4dc7e40b4b05b63c0d39cc3a2f27 is 1.286ms for 5 entries.58client # [6334046.053112] client systemd-journald[69]: System Journal (/var/log/journal/2a5a4dc7e40b4b05b63c0d39cc3a2f27) is 8M, max 4G, 3.9G free.59client # [6334046.057897] client systemd[1]: Finished Create Static Device Nodes in /dev.60client # [6334046.058142] client systemd[1]: Reached target Preparation for Local File Systems.61client # [6334046.058226] client systemd[1]: Reached target Local File Systems.62client # [6334046.058954] client systemd[1]: Listening on Boot Loader Control Service Socket.63client # [6334046.058996] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container64client # [6334046.059847] client systemd[1]: Starting Save Transient machine-id to Disk...65client # [6334046.059881] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys66client # [6334046.065889] client systemd[1]: Finished Flush Journal to Persistent Storage.67client # [6334046.067257] client systemd[1]: Starting Create System Files and Directories...68client # [6334046.081445] client systemd-tmpfiles[108]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted69client # [6334046.081621] client systemd-tmpfiles[108]: fchmod() of /var/log/journal failed: Operation not permitted70client # [6334046.081740] client systemd-tmpfiles[108]: fchmod() of /var/log/journal/2a5a4dc7e40b4b05b63c0d39cc3a2f27 failed: Operation not permitted71client # [6334046.081927] client systemd-tmpfiles[108]: fchmod() of /run/log/journal failed: Operation not permitted72client # [6334046.083435] client systemd[1]: Finished Create System Files and Directories.73client # [6334046.084541] client systemd[1]: Starting Rebuild Journal Catalog...74client # [6334046.085258] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...75client # [6334046.097434] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.76client # [6334046.106744] client systemd[1]: Finished Rebuild Journal Catalog.77client # [6334046.107838] client systemd[1]: Starting Update is Completed...78client # [6334046.117685] client systemd[1]: Finished Update is Completed.79client # [6334046.184177] client systemd[1]: Finished Firewall.80client # [6334046.184316] client systemd[1]: Reached target Preparation for Network.81client # [6334046.184513] client systemd[1]: Listening on Network Management Resolve Hook Socket.82client # [6334046.185538] client systemd[1]: Starting Network Management...83server # [6334046.236334] server systemd[1]: Finished Firewall.84server # [6334046.236523] server systemd[1]: Reached target Preparation for Network.85server # [6334046.236730] server systemd[1]: Listening on Network Management Resolve Hook Socket.86server # [6334046.237734] server systemd[1]: Starting Network Management...87server # [6334046.667263] server systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted88server # [6334046.667357] server systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted89server # [6334046.675335] 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.90server # [6334046.675501] 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.91server # [6334046.675741] server systemd-networkd[213]: lo: Link UP92server # [6334046.675745] server systemd-networkd[213]: lo: Gained carrier93server # [6334046.675930] server systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.94server # [6334046.676529] server systemd[1]: Started Network Management.95server # [6334046.676628] server systemd-networkd[213]: eth1: Link UP96server # [6334046.676868] server systemd-networkd[213]: eth1: Gained carrier97server # [6334046.677506] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...98server # [6334046.721885] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.99server # [6334046.740794] server systemd[1]: Finished Save Transient machine-id to Disk.100server # [6334046.777307] server systemd-resolved[118]: Positive Trust Anchors:101server # [6334046.777318] server systemd-resolved[118]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d102server # [6334046.777321] server systemd-resolved[118]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16103server # [6334046.777356] server 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 test104server # [6334046.799534] server systemd-resolved[118]: Using system hostname 'server'.105server # [6334046.800888] server systemd[1]: Started Network Name Resolution.106server # [6334046.800954] server systemd[1]: Reached target Network.107server # [6334046.801019] server systemd[1]: Reached target System Initialization.108server # [6334046.801066] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container109server # [6334046.801093] server systemd[1]: Started Daily Cleanup of Temporary Directories.110server # [6334046.801107] server systemd[1]: Reached target Timer Units.111server # [6334046.801220] server systemd[1]: Listening on D-Bus System Message Bus Socket.112server # [6334046.801328] server systemd[1]: Listening on Nix Daemon Socket.113server # [6334046.802270] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).114server # [6334046.803105] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).115server # [6334046.803547] server systemd[1]: Listening on SSH Socket.116server # [6334046.803650] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.117server # [6334046.803672] server systemd[1]: Reached target Socket Units.118server # [6334046.803718] server systemd[1]: Reached target Basic System.119server # [6334046.804734] server systemd[1]: Starting Import lastlog data into lastlog2 database...120server # [6334046.805537] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...121server # [6334046.805571] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.122server # [6334046.807117] server systemd[1]: Starting D-Bus System Message Bus...123server # [6334046.824323] server systemd[1]: Finished Import lastlog data into lastlog2 database.124server # [6334046.920868] server nsncd[220]: Aug 21 06:51:12.973 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"125server # [6334046.920259] server systemd[1]: Started Name Service Cache Daemon (nsncd).126server # [6334046.920312] server systemd[1]: Reached target Host and Network Name Lookups.127server # [6334046.920377] server systemd[1]: Reached target User and Group Name Lookups.128client # [6334046.701357] client systemd-networkd[182]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted129client # [6334046.701446] client systemd-networkd[182]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted130client # [6334046.708015] client systemd-networkd[182]: /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.131client # [6334046.708183] client systemd-networkd[182]: /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.132client # [6334046.708341] client systemd-networkd[182]: lo: Link UP133client # [6334046.708346] client systemd-networkd[182]: lo: Gained carrier134client # [6334046.708541] client systemd-networkd[182]: eth1: Configuring with /etc/systemd/network/40-eth1.network.135client # [6334046.708908] client systemd[1]: Started Network Management.136client # [6334046.708976] client systemd-networkd[182]: eth1: Link UP137client # [6334046.709170] client systemd-networkd[182]: eth1: Gained carrier138client # [6334046.709912] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...139client # [6334046.738102] client systemd[1]: Finished Save Transient machine-id to Disk.140client # [6334046.753827] client systemd-resolved[89]: Positive Trust Anchors:141client # [6334046.753838] client systemd-resolved[89]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d142client # [6334046.753842] client systemd-resolved[89]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16143client # [6334046.753879] client systemd-resolved[89]: 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 test144client # [6334046.757795] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.145client # [6334046.776859] client systemd-resolved[89]: Using system hostname 'client'.146client # [6334046.778331] client systemd[1]: Started Network Name Resolution.147client # [6334046.778401] client systemd[1]: Reached target Network.148client # [6334046.778465] client systemd[1]: Reached target System Initialization.149client # [6334046.778513] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container150client # [6334046.778541] client systemd[1]: Started Daily Cleanup of Temporary Directories.151client # [6334046.778558] client systemd[1]: Reached target Timer Units.152client # [6334046.778668] client systemd[1]: Listening on D-Bus System Message Bus Socket.153client # [6334046.778774] client systemd[1]: Listening on Nix Daemon Socket.154client # [6334046.778874] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.155client # [6334046.778891] client systemd[1]: Reached target Socket Units.156client # [6334046.778922] client systemd[1]: Reached target Basic System.157client # [6334046.779935] client systemd[1]: Starting Import lastlog data into lastlog2 database...158client # [6334046.780757] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...159client # [6334046.781898] client systemd[1]: Starting D-Bus System Message Bus...160client # [6334046.799812] client systemd[1]: Finished Import lastlog data into lastlog2 database.161client # [6334046.906979] client nsncd[189]: Aug 21 06:51:12.960 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"162client # [6334046.907037] client systemd[1]: Started Name Service Cache Daemon (nsncd).163client # [6334046.907093] client systemd[1]: Reached target Host and Network Name Lookups.164client # [6334046.907151] client systemd[1]: Reached target User and Group Name Lookups.165client # [6334046.908285] client systemd[1]: Starting User Login Management...166client # [6334046.909122] client systemd[1]: Starting Permit User Sessions...167client # [6334046.922444] client systemd[1]: Finished Permit User Sessions.168client # [6334046.923430] client systemd[1]: Started Console Getty.169client # [6334046.923471] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0170client # [6334046.923489] client systemd[1]: Reached target Login Prompts.171server # [6334046.921554] server systemd[1]: Starting User Login Management...172server # [6334046.922265] server systemd[1]: Starting Permit User Sessions...173server # [6334046.932267] server systemd[1]: Finished Permit User Sessions.174server # [6334046.933392] server systemd[1]: Started Console Getty.175server # [6334046.933439] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0176server # [6334046.933461] server systemd[1]: Reached target Login Prompts.177server # [6334047.005742] server dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...178server # [6334047.006468] server dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'179server # [6334047.006468] server dbus-broker-launch[221]: Invalid user-name in /nix/store/gabyj95zn3grnr62i272q711skbg36hb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"180server # [6334047.006813] server systemd[1]: Started D-Bus System Message Bus.181server # [6334047.014407] server dbus-broker-launch[221]: Ready182server # [6334047.043417] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.183client # [6334047.001834] client dbus-broker-launch[190]: Looking up NSS user entry for 'systemd-timesync'...184client # [6334047.004267] client dbus-broker-launch[190]: NSS returned no entry for 'systemd-timesync'185client # [6334047.004267] client dbus-broker-launch[190]: Invalid user-name in /nix/store/v0qgwjqpmqxxvck906x1kby2gvvgwg6y-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"186client # [6334047.004703] client systemd[1]: Started D-Bus System Message Bus.187client # [6334047.011927] client dbus-broker-launch[190]: Ready188client # [6334047.021359] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.189client # [6334047.399556] client systemd-logind[205]: New seat seat0.190server # [6334047.411516] server systemd-logind[236]: New seat seat0.191server # [6334047.411717] server systemd[1]: Started User Login Management.192server # [6334047.440736] server systemd[1]: Starting linger-users.service...193server # [6334047.458963] server systemd[1]: linger-users.service: Deactivated successfully.194server # [6334047.459144] server systemd[1]: Finished linger-users.service.195server # [6334047.459637] server systemd[1]: Reached target Multi-User System.196server # [6334047.459874] server systemd[1]: Startup finished in 1.741s.197client # [6334047.400136] client systemd[1]: Started User Login Management.198client # [6334047.401324] client systemd[1]: Starting linger-users.service...199client # [6334047.453640] client systemd[1]: linger-users.service: Deactivated successfully.200client # [6334047.453906] client systemd[1]: Finished linger-users.service.201client # [6334047.454788] client systemd[1]: Reached target Multi-User System.202client # [6334047.455083] client systemd[1]: Startup finished in 1.805s.203client: waiting for unit network-online.target204client: (finished: waiting for unit network-online.target, in 0.03 seconds)205server: waiting for unit network-online.target206server: (finished: waiting for unit network-online.target, in 0.02 seconds)207client: 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/null208client: (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)209(finished: run the VM test script, in 2.90 seconds)210test script finished in 2.94s211cleanup212kill NspawnMachine (pid 52)213client # [6334047.776344] client systemd-networkd[182]: eth1: Gained IPv6LL214client # [6334047.783820] client systemd[1]: Reached target Network is Online.215client # [6334048.083993] client systemd-networkd[182]: eth1: Link DOWN216server # [6334047.801462] server systemd[1]: Reached target Network is Online.217server # [6334047.872312] server systemd-networkd[213]: eth1: Gained IPv6LL218kill NspawnMachine (pid 53)219server # [6334047.912698] server systemd[1]: Created slice Slice /system/sshd.220server # [6334047.912799] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.221server # [6334047.914174] server systemd[1]: Started SSH per-connection Daemon (192.168.1.1:33950).222server # [6334048.017325] server sshd-session[252]: Accepted publickey for root from 192.168.1.1 port 33950 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ223server # [6334048.034476] server sshd-session[256]: Received disconnect from 192.168.1.1 port 33950:11: disconnected by user224server # [6334048.034679] server sshd-session[256]: Disconnected from user root 192.168.1.1 port 33950225server # [6334048.036024] server systemd[1]: sshd@0-245812-192.168.1.2:22-192.168.1.1:33950.service: Deactivated successfully.226Container client terminated by signal KILL.227Container server terminated by signal KILL.228(finished: cleanup, in 0.38 seconds)