container-test-run-admin
checks.aarch64-linux.admin
· build #505
· 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 # [6785513.839197] server systemd-journald[98]: Journal started26server # [6785513.839249] server systemd-journald[98]: Runtime Journal (/run/log/journal/4dd5e3a362464dfaba368ec136520821) is 8M, max 2.5G, 2.4G free.27server # [6785513.841233] server systemd[1]: Finished Create Static Device Nodes in /dev gracefully.28server # [6785513.850756] server systemd[1]: Starting Flush Journal to Persistent Storage...29server # [6785513.851654] server systemd[1]: Starting Network Name Resolution...30server # [6785513.852387] server systemd[1]: Starting Create Static Device Nodes in /dev...31server # [6785513.859929] server systemd-journald[98]: Time spent on flushing to /var/log/journal/4dd5e3a362464dfaba368ec136520821 is 1.486ms for 6 entries.32server # [6785513.859929] server systemd-journald[98]: System Journal (/var/log/journal/4dd5e3a362464dfaba368ec136520821) is 8M, max 4G, 3.9G free.33server # [6785513.870528] server systemd[1]: Finished Create Static Device Nodes in /dev.34server # [6785513.870793] server systemd[1]: Reached target Preparation for Local File Systems.35server # [6785513.870880] server systemd[1]: Reached target Local File Systems.36server # [6785513.871625] server systemd[1]: Listening on Boot Loader Control Service Socket.37server # [6785513.871668] server systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container38server # [6785513.872526] server systemd[1]: Starting Save Transient machine-id to Disk...39server # [6785513.872564] server systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys40server # [6785513.877097] server systemd[1]: Finished Flush Journal to Persistent Storage.41server # [6785513.878032] server systemd[1]: Starting Create System Files and Directories...42server # [6785513.893401] server systemd-tmpfiles[142]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted43server # [6785513.893572] server systemd-tmpfiles[142]: fchmod() of /var/log/journal failed: Operation not permitted44server # [6785513.893691] server systemd-tmpfiles[142]: fchmod() of /var/log/journal/4dd5e3a362464dfaba368ec136520821 failed: Operation not permitted45server # [6785513.893877] server systemd-tmpfiles[142]: fchmod() of /run/log/journal failed: Operation not permitted46server # [6785513.895848] server systemd[1]: Finished Create System Files and Directories.47server # [6785513.896924] server systemd[1]: Starting Rebuild Journal Catalog...48server # [6785513.897626] server systemd[1]: Starting Record System Boot/Shutdown in UTMP...49server # [6785513.909697] server systemd[1]: Finished Record System Boot/Shutdown in UTMP.50server # [6785513.916498] server systemd[1]: Finished Rebuild Journal Catalog.51server # [6785513.917523] server systemd[1]: Starting Update is Completed...52server # [6785513.927894] server systemd[1]: Finished Update is Completed.53server # [6785513.990073] server systemd[1]: Finished Firewall.54server # [6785513.990224] server systemd[1]: Reached target Preparation for Network.55server # [6785513.990440] server systemd[1]: Listening on Network Management Resolve Hook Socket.56server # [6785513.991569] server systemd[1]: Starting Network Management...57client # [6785513.784467] client systemd-journald[69]: Journal started58client # [6785513.784526] client systemd-journald[69]: Runtime Journal (/run/log/journal/712923d96eed4e1c8503b7a250dc19ff) is 8M, max 2.5G, 2.4G free.59client # [6785513.788297] client systemd[1]: Starting Flush Journal to Persistent Storage...60client # [6785513.789212] client systemd[1]: Starting Network Name Resolution...61client # [6785513.789955] client systemd[1]: Starting Create Static Device Nodes in /dev...62client # [6785513.799675] client systemd-journald[69]: Time spent on flushing to /var/log/journal/712923d96eed4e1c8503b7a250dc19ff is 1.481ms for 5 entries.63client # [6785513.799675] client systemd-journald[69]: System Journal (/var/log/journal/712923d96eed4e1c8503b7a250dc19ff) is 8M, max 4G, 3.9G free.64client # [6785513.802440] client systemd[1]: Finished Create Static Device Nodes in /dev.65client # [6785513.802657] client systemd[1]: Reached target Preparation for Local File Systems.66client # [6785513.802743] client systemd[1]: Reached target Local File Systems.67client # [6785513.803457] client systemd[1]: Listening on Boot Loader Control Service Socket.68client # [6785513.803497] client systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container69client # [6785513.804562] client systemd[1]: Starting Save Transient machine-id to Disk...70client # [6785513.804597] client systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys71client # [6785513.823131] client systemd[1]: Finished Flush Journal to Persistent Storage.72client # [6785513.824135] client systemd[1]: Starting Create System Files and Directories...73client # [6785513.839314] client systemd-tmpfiles[116]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted74client # [6785513.839508] client systemd-tmpfiles[116]: fchmod() of /var/log/journal failed: Operation not permitted75client # [6785513.839640] client systemd-tmpfiles[116]: fchmod() of /var/log/journal/712923d96eed4e1c8503b7a250dc19ff failed: Operation not permitted76client # [6785513.839840] client systemd-tmpfiles[116]: fchmod() of /run/log/journal failed: Operation not permitted77client # [6785513.841647] client systemd[1]: Finished Create System Files and Directories.78client # [6785513.842784] client systemd[1]: Starting Rebuild Journal Catalog...79client # [6785513.843527] client systemd[1]: Starting Record System Boot/Shutdown in UTMP...80client # [6785513.855570] client systemd[1]: Finished Record System Boot/Shutdown in UTMP.81client # [6785513.863620] client systemd[1]: Finished Rebuild Journal Catalog.82client # [6785513.864724] client systemd[1]: Starting Update is Completed...83client # [6785513.876664] client systemd[1]: Finished Update is Completed.84client # [6785513.925127] client systemd[1]: Finished Firewall.85client # [6785513.925272] client systemd[1]: Reached target Preparation for Network.86client # [6785513.925483] client systemd[1]: Listening on Network Management Resolve Hook Socket.87client # [6785513.926530] client systemd[1]: Starting Network Management...88client # [6785514.311497] client systemd-networkd[182]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted89client # [6785514.311593] client systemd-networkd[182]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted90client # [6785514.318652] 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.91client # [6785514.318825] 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.92client # [6785514.319210] client systemd-networkd[182]: lo: Link UP93client # [6785514.319216] client systemd-networkd[182]: lo: Gained carrier94client # [6785514.319405] client systemd-networkd[182]: eth1: Configuring with /etc/systemd/network/40-eth1.network.95client # [6785514.319913] client systemd-networkd[182]: eth1: Link UP96client # [6785514.320153] client systemd-networkd[182]: eth1: Gained carrier97client # [6785514.320161] client systemd[1]: Started Network Management.98client # [6785514.321249] client systemd[1]: Starting Enable Persistent Storage in systemd-networkd...99client # [6785514.362107] client systemd[1]: Finished Enable Persistent Storage in systemd-networkd.100client # [6785514.461263] client systemd-resolved[92]: Positive Trust Anchors:101client # [6785514.461275] client systemd-resolved[92]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d102client # [6785514.461278] client systemd-resolved[92]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16103client # [6785514.461313] client systemd-resolved[92]: 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 test104client # [6785514.483569] client systemd-resolved[92]: Using system hostname 'client'.105client # [6785514.484978] client systemd[1]: Started Network Name Resolution.106client # [6785514.485056] client systemd[1]: Reached target Network.107client # [6785514.485111] client systemd[1]: Reached target System Initialization.108client # [6785514.485155] client systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container109client # [6785514.485184] client systemd[1]: Started Daily Cleanup of Temporary Directories.110client # [6785514.485198] client systemd[1]: Reached target Timer Units.111client # [6785514.485316] client systemd[1]: Listening on D-Bus System Message Bus Socket.112client # [6785514.485445] client systemd[1]: Listening on Nix Daemon Socket.113client # [6785514.485548] client systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.114client # [6785514.485568] client systemd[1]: Reached target Socket Units.115client # [6785514.485602] client systemd[1]: Reached target Basic System.116client # [6785514.486660] client systemd[1]: Starting Import lastlog data into lastlog2 database...117client # [6785514.487732] client systemd[1]: Starting Name Service Cache Daemon (nsncd)...118client # [6785514.489173] client systemd[1]: Starting D-Bus System Message Bus...119server # [6785514.375516] server systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted120server # [6785514.375608] server systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted121server # [6785514.383204] 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.122server # [6785514.383371] 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.123server # [6785514.383546] server systemd-networkd[213]: lo: Link UP124server # [6785514.383551] server systemd-networkd[213]: lo: Gained carrier125server # [6785514.383741] server systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.126server # [6785514.384174] server systemd[1]: Started Network Management.127server # [6785514.384242] server systemd-networkd[213]: eth1: Link UP128server # [6785514.384607] server systemd-networkd[213]: eth1: Gained carrier129server # [6785514.385325] server systemd[1]: Starting Enable Persistent Storage in systemd-networkd...130server # [6785514.450026] server systemd[1]: Finished Enable Persistent Storage in systemd-networkd.131server # [6785514.529519] server systemd-resolved[121]: Positive Trust Anchors:132server # [6785514.529532] server systemd-resolved[121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d133server # [6785514.529536] server systemd-resolved[121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16134server # [6785514.529571] server systemd-resolved[121]: 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 test135server # [6785514.551956] server systemd-resolved[121]: Using system hostname 'server'.136server # [6785514.553308] server systemd[1]: Started Network Name Resolution.137server # [6785514.553387] server systemd[1]: Reached target Network.138server # [6785514.553453] server systemd[1]: Reached target System Initialization.139server # [6785514.553511] server systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container140server # [6785514.553544] server systemd[1]: Started Daily Cleanup of Temporary Directories.141server # [6785514.553566] server systemd[1]: Reached target Timer Units.142server # [6785514.553695] server systemd[1]: Listening on D-Bus System Message Bus Socket.143server # [6785514.553830] server systemd[1]: Listening on Nix Daemon Socket.144server # [6785514.555052] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).145server # [6785514.556221] server systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).146server # [6785514.556805] server systemd[1]: Listening on SSH Socket.147server # [6785514.556927] server systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.148server # [6785514.556953] server systemd[1]: Reached target Socket Units.149server # [6785514.557002] server systemd[1]: Reached target Basic System.150server # [6785514.558475] server systemd[1]: Starting Import lastlog data into lastlog2 database...151server # [6785514.559412] server systemd[1]: Starting Name Service Cache Daemon (nsncd)...152server # [6785514.559445] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.153server # [6785514.561092] server systemd[1]: Starting D-Bus System Message Bus...154server # [6785514.582192] server systemd[1]: Finished Import lastlog data into lastlog2 database.155server # [6785514.660520] server nsncd[219]: Aug 26 12:15:40.713 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"156server # [6785514.660523] server systemd[1]: Started Name Service Cache Daemon (nsncd).157server # [6785514.660617] server systemd[1]: Reached target Host and Network Name Lookups.158server # [6785514.660722] server systemd[1]: Reached target User and Group Name Lookups.159client # [6785514.574524] client systemd[1]: Finished Import lastlog data into lastlog2 database.160client # [6785514.704088] client systemd[1]: Started Name Service Cache Daemon (nsncd).161client # [6785514.704150] client systemd[1]: Reached target Host and Network Name Lookups.162client # [6785514.704440] client nsncd[188]: Aug 26 12:15:40.757 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"163client # [6785514.704201] client systemd[1]: Reached target User and Group Name Lookups.164client # [6785514.708478] client systemd[1]: Starting User Login Management...165client # [6785514.709401] client systemd[1]: Starting Permit User Sessions...166client # [6785514.720893] client systemd[1]: Finished Permit User Sessions.167client # [6785514.722085] client systemd[1]: Started Console Getty.168client # [6785514.722138] client systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0169client # [6785514.722162] client systemd[1]: Reached target Login Prompts.170client # [6785514.773432] client dbus-broker-launch[189]: Looking up NSS user entry for 'systemd-timesync'...171client # [6785514.775514] client dbus-broker-launch[189]: NSS returned no entry for 'systemd-timesync'172client # [6785514.775514] client dbus-broker-launch[189]: Invalid user-name in /nix/store/5a0c1jkfwi21h99izphv1z1r8qyn2m1d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"173client # [6785514.775239] client systemd[1]: Started D-Bus System Message Bus.174client # [6785514.782784] client dbus-broker-launch[189]: Ready175server # [6785514.662904] server systemd[1]: Starting User Login Management...176server # [6785514.664274] server systemd[1]: Starting Permit User Sessions...177server # [6785514.716282] server systemd[1]: Finished Permit User Sessions.178server # [6785514.717974] server systemd[1]: Started Console Getty.179server # [6785514.718047] server systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0180server # [6785514.718086] server systemd[1]: Reached target Login Prompts.181server # [6785514.735292] server dbus-broker-launch[220]: Looking up NSS user entry for 'systemd-timesync'...182server # [6785514.736078] server dbus-broker-launch[220]: NSS returned no entry for 'systemd-timesync'183server # [6785514.736078] server dbus-broker-launch[220]: Invalid user-name in /nix/store/j245x9qbkgxzbfj4g0h63akgpd2jm3cp-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"184server # [6785514.736533] server systemd[1]: Started D-Bus System Message Bus.185server # [6785514.748742] server dbus-broker-launch[220]: Ready186client # [6785515.156464] client systemd-logind[204]: New seat seat0.187client # [6785515.156667] client systemd[1]: Started User Login Management.188client # [6785515.157692] client systemd[1]: Starting linger-users.service...189client # [6785515.171068] client systemd[1]: linger-users.service: Deactivated successfully.190client # [6785515.171207] client systemd[1]: Finished linger-users.service.191client # [6785515.171587] client systemd[1]: Reached target Multi-User System.192server # [6785515.156308] server systemd-logind[235]: New seat seat0.193server # [6785515.156536] server systemd[1]: Started User Login Management.194server # [6785515.157691] server systemd[1]: Starting linger-users.service...195server # [6785515.169366] server systemd[1]: linger-users.service: Deactivated successfully.196server # [6785515.169498] server systemd[1]: Finished linger-users.service.197server # [6785515.170421] server systemd[1]: Reached target Multi-User System.198client # [6785515.520250] client systemd-networkd[182]: eth1: Gained IPv6LL199server # [6785516.260169] server systemd-networkd[213]: eth1: Gained IPv6LL200client # [6785516.826625] client systemd[1]: etc-machine\x2did.mount: Deactivated successfully.201client # [6785516.884335] client systemd[1]: Finished Save Transient machine-id to Disk.202client # [6785516.884706] client systemd[1]: Startup finished in 3.468s.203server # [6785516.879882] server systemd[1]: etc-machine\x2did.mount: Deactivated successfully.204server # [6785516.884384] server systemd[1]: Finished Save Transient machine-id to Disk.205server # [6785516.884774] server systemd[1]: Startup finished in 3.380s.206server # [6785517.148625] server systemd[1]: Reached target Network is Online.207client: waiting for unit network-online.target208client: (finished: waiting for unit network-online.target, in 0.02 seconds)209server: waiting for unit network-online.target210server: (finished: waiting for unit network-online.target, in 0.01 seconds)211client: 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/null212client: (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.20 seconds)213(finished: run the VM test script, in 4.40 seconds)214server # [6785517.194661] server systemd[1]: Created slice Slice /system/sshd.215server # [6785517.194738] server systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.216server # [6785517.195652] server systemd[1]: Started SSH per-connection Daemon ([2001:db8:1::1]:51254).217server # [6785517.364179] server sshd-session[252]: Accepted publickey for root from 2001:db8:1::1 port 51254 ssh2: ED25519 SHA256:8t391Em0YyXoWjQ3FBfwMyyV52RJVlM39qcyv1jpWsQ218server # [6785517.380555] server sshd-session[256]: Received disconnect from 2001:db8:1::1 port 51254:11: disconnected by user219server # [6785517.380743] server sshd-session[256]: Disconnected from user root 2001:db8:1::1 port 51254220server # [6785517.381786] server systemd[1]: sshd@0-303160-2001:db8:1::2:22-2001:db8:1::1:51254.service: Deactivated successfully.221client # [6785517.134302] client systemd[1]: Reached target Network is Online.222test script finished in 5.17s223cleanup224kill NspawnMachine (pid 52)225kill NspawnMachine (pid 53)226Container client terminated by signal KILL.227Container server terminated by signal KILL.228(finished: cleanup, in 0.43 seconds)