container-test-run-hello-service
checks.aarch64-linux.hello-service
· build #1009
· raw
1build01: building lpvz7drs339amns95nh8zd8n54i2lkhv-container-test-run-hello-service.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)56Test will time out and terminate in 3600.0 seconds7run the VM test script8additionally exposed symbols:9 peer1, peer2,10 vlan1,11 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_ssh12start all VMs13peer2: systemd-nspawn running (pid 53)14peer1: systemd-nspawn running (pid 52)15peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...16peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...17(finished: start all VMs, in 0.00 seconds)18peer1: must succeed: greet-world19nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE20nixos-nspawn(peer2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.21nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE22nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.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 peer2 on /build/vm-state-peer2.25Note: 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.26░ Spawning container peer1 on /build/vm-state-peer1.27peer1 # [38497.839437] peer1 systemd-journald[72]: Journal started28peer1 # [38497.839494] peer1 systemd-journald[72]: Runtime Journal (/run/log/journal/2cb651de86734e78abd5fc1e061b01ba) is 8M, max 2.5G, 2.4G free.29peer1 # [38497.844298] peer1 systemd[1]: Finished Apply Kernel Variables.30peer1 # [38497.855107] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.31peer1 # [38497.867482] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...32peer1 # [38497.868320] peer1 systemd[1]: Starting Network Name Resolution...33peer1 # [38497.868991] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...34peer1 # [38497.878507] peer1 systemd-journald[72]: Time spent on flushing to /var/log/journal/2cb651de86734e78abd5fc1e061b01ba is 1.079ms for 7 entries.35peer1 # [38497.878507] peer1 systemd-journald[72]: System Journal (/var/log/journal/2cb651de86734e78abd5fc1e061b01ba) is 8M, max 4G, 3.9G free.36peer1 # [38497.891074] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.37peer1 # [38497.891718] peer1 systemd[1]: Reached target Preparation for Local File Systems.38peer2 # [38497.833242] peer2 systemd-journald[72]: Journal started39peer1 # [38497.891843] peer1 systemd[1]: Reached target Local File Systems.40peer2 # [38497.833303] peer2 systemd-journald[72]: Runtime Journal (/run/log/journal/310ef733d4ae473c9a5155f5567ea5ac) is 8M, max 2.5G, 2.4G free.41peer1 # [38497.892701] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.42peer2 # [38497.844881] peer2 systemd[1]: Finished Apply Kernel Variables.43peer1 # [38497.892753] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container44peer2 # [38497.857571] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.45peer1 # [38497.893668] peer1 systemd[1]: Starting Save Transient machine-id to Disk...46peer2 # [38497.872472] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...47peer1 # [38497.893704] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys48peer2 # [38497.873322] peer2 systemd[1]: Starting Network Name Resolution...49peer1 # [38497.895905] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.50peer2 # [38497.874045] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...51peer1 # [38497.897030] peer1 systemd[1]: Starting Create System Files and Directories...52peer2 # [38497.884192] peer2 systemd-journald[72]: Time spent on flushing to /var/log/journal/310ef733d4ae473c9a5155f5567ea5ac is 1.455ms for 7 entries.53peer1 # [38497.915243] peer1 systemd-tmpfiles[121]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted54peer2 # [38497.884192] peer2 systemd-journald[72]: System Journal (/var/log/journal/310ef733d4ae473c9a5155f5567ea5ac) is 8M, max 4G, 3.9G free.55peer2 # [38497.899292] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.56peer1 # [38497.915441] peer1 systemd-tmpfiles[121]: fchmod() of /var/log/journal failed: Operation not permitted57peer2 # [38497.899918] peer2 systemd[1]: Reached target Preparation for Local File Systems.58peer2 # [38497.900058] peer2 systemd[1]: Reached target Local File Systems.59peer1 # [38497.915580] peer1 systemd-tmpfiles[121]: fchmod() of /var/log/journal/2cb651de86734e78abd5fc1e061b01ba failed: Operation not permitted60peer2 # [38497.900950] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.61peer1 # [38497.915784] peer1 systemd-tmpfiles[121]: fchmod() of /run/log/journal failed: Operation not permitted62peer2 # [38497.900999] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container63peer1 # [38497.917235] peer1 systemd[1]: Finished Create System Files and Directories.64peer2 # [38497.901814] peer2 systemd[1]: Starting Save Transient machine-id to Disk...65peer1 # [38497.918357] peer1 systemd[1]: Starting Rebuild Journal Catalog...66peer2 # [38497.901846] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys67peer1 # [38497.919071] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...68peer2 # [38497.904396] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.69peer2 # [38497.905229] peer2 systemd[1]: Starting Create System Files and Directories...70peer2 # [38497.923966] peer2 systemd-tmpfiles[130]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted71peer2 # [38497.924520] peer2 systemd-tmpfiles[130]: fchmod() of /var/log/journal failed: Operation not permitted72peer2 # [38497.924656] peer2 systemd-tmpfiles[130]: fchmod() of /var/log/journal/310ef733d4ae473c9a5155f5567ea5ac failed: Operation not permitted73peer2 # [38497.924852] peer2 systemd-tmpfiles[130]: fchmod() of /run/log/journal failed: Operation not permitted74peer1 # [38497.930755] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.75peer1 # [38497.937373] peer1 systemd[1]: Finished Rebuild Journal Catalog.76peer1 # [38497.938539] peer1 systemd[1]: Starting Update is Completed...77peer1 # [38497.951647] peer1 systemd[1]: Finished Update is Completed.78peer1 # [38498.008982] peer1 systemd[1]: Finished Firewall.79peer1 # [38498.009156] peer1 systemd[1]: Reached target Preparation for Network.80peer1 # [38498.009374] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.81peer1 # [38498.010442] peer1 systemd[1]: Starting Network Management...82peer1 # [38498.011878] peer1 systemd[1]: Finished Save Transient machine-id to Disk.83peer2 # [38497.926297] peer2 systemd[1]: Finished Create System Files and Directories.84peer2 # [38497.927377] peer2 systemd[1]: Starting Rebuild Journal Catalog...85peer2 # [38497.928050] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...86peer2 # [38497.940256] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.87peer2 # [38497.951477] peer2 systemd[1]: Finished Rebuild Journal Catalog.88peer2 # [38497.952859] peer2 systemd[1]: Starting Update is Completed...89peer2 # [38497.964675] peer2 systemd[1]: Finished Update is Completed.90peer2 # [38497.996832] peer2 systemd[1]: Finished Firewall.91peer2 # [38497.997645] peer2 systemd[1]: Reached target Preparation for Network.92peer2 # [38497.998105] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.93peer2 # [38497.999893] peer2 systemd[1]: Starting Network Management...94peer2 # [38498.011455] peer2 systemd[1]: Finished Save Transient machine-id to Disk.95peer1 # [38498.615508] peer1 systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted96peer1 # [38498.615609] peer1 systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted97peer1 # [38498.625836] peer1 systemd-networkd[185]: /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.98peer1 # [38498.626004] peer1 systemd-networkd[185]: /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.99peer1 # [38498.626161] peer1 systemd-networkd[185]: lo: Link UP100peer1 # [38498.626165] peer1 systemd-networkd[185]: lo: Gained carrier101peer1 # [38498.626335] peer1 systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.102peer1 # [38498.626772] peer1 systemd[1]: Started Network Management.103peer1 # [38498.626808] peer1 systemd-networkd[185]: eth1: Link UP104peer1 # [38498.627075] peer1 systemd-networkd[185]: eth1: Gained carrier105peer1 # [38498.628199] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...106peer1 # [38498.684705] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.107peer1 # [38498.837234] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.108peer2 # [38498.630459] peer2 systemd-networkd[185]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted109peer2 # [38498.630544] peer2 systemd-networkd[185]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted110peer2 # [38498.639426] peer2 systemd-networkd[185]: /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.111peer2 # [38498.639591] peer2 systemd-networkd[185]: /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.112peer2 # [38498.639717] peer2 systemd-networkd[185]: lo: Link UP113peer2 # [38498.639722] peer2 systemd-networkd[185]: lo: Gained carrier114peer2 # [38498.639902] peer2 systemd-networkd[185]: eth1: Configuring with /etc/systemd/network/40-eth1.network.115peer2 # [38498.640224] peer2 systemd[1]: Started Network Management.116peer2 # [38498.676386] peer2 systemd-networkd[185]: eth1: Link UP117peer2 # [38498.676569] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...118peer2 # [38498.676640] peer2 systemd-networkd[185]: eth1: Gained carrier119peer2 # [38498.726291] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.120peer2 # [38498.830719] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.121peer1 # [38498.930211] peer1 systemd-resolved[101]: Positive Trust Anchors:122peer1 # [38498.930222] peer1 systemd-resolved[101]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d123peer1 # [38498.930227] peer1 systemd-resolved[101]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16124peer1 # [38498.930261] peer1 systemd-resolved[101]: 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 test125peer1 # [38498.950624] peer1 systemd-resolved[101]: Using system hostname 'peer1'.126peer1 # [38498.951981] peer1 systemd[1]: Started Network Name Resolution.127peer1 # [38498.952093] peer1 systemd[1]: Reached target Network.128peer1 # [38498.952169] peer1 systemd[1]: Reached target System Initialization.129peer1 # [38498.952226] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container130peer1 # [38498.952264] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.131peer1 # [38498.952288] peer1 systemd[1]: Reached target Timer Units.132peer1 # [38498.952439] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.133peer1 # [38498.952566] peer1 systemd[1]: Listening on Nix Daemon Socket.134peer1 # [38498.952702] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.135peer1 # [38498.952727] peer1 systemd[1]: Reached target Socket Units.136peer1 # [38498.952771] peer1 systemd[1]: Reached target Basic System.137peer1 # [38498.988902] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...138peer1 # [38498.989930] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...139peer1 # [38498.991626] peer1 systemd[1]: Starting D-Bus System Message Bus...140peer1 # [38499.009384] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.141peer1 # [38499.198989] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).142peer1 # [38499.199245] peer1 nsncd[192]: Oct 16 20:05:04.170 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"143peer1 # [38499.199061] peer1 systemd[1]: Reached target Host and Network Name Lookups.144peer1 # [38499.199126] peer1 systemd[1]: Reached target User and Group Name Lookups.145peer2 # [38498.945092] peer2 systemd-resolved[108]: Positive Trust Anchors:146peer2 # [38498.945104] peer2 systemd-resolved[108]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d147peer2 # [38498.945108] peer2 systemd-resolved[108]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16148peer2 # [38498.945143] peer2 systemd-resolved[108]: 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 test149peer2 # [38498.965523] peer2 systemd-resolved[108]: Using system hostname 'peer2'.150peer2 # [38498.967119] peer2 systemd[1]: Started Network Name Resolution.151peer2 # [38498.967237] peer2 systemd[1]: Reached target Network.152peer2 # [38498.967350] peer2 systemd[1]: Reached target System Initialization.153peer2 # [38498.967444] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container154peer2 # [38498.967502] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.155peer2 # [38498.967538] peer2 systemd[1]: Reached target Timer Units.156peer2 # [38498.967755] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.157peer2 # [38498.967953] peer2 systemd[1]: Listening on Nix Daemon Socket.158peer2 # [38498.968206] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.159peer2 # [38498.968253] peer2 systemd[1]: Reached target Socket Units.160peer2 # [38498.968340] peer2 systemd[1]: Reached target Basic System.161peer2 # [38498.989319] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...162peer2 # [38498.991230] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...163peer2 # [38498.993393] peer2 systemd[1]: Starting D-Bus System Message Bus...164peer2 # [38499.010382] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.165peer2 # [38499.243823] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).166peer2 # [38499.244098] peer2 nsncd[192]: Oct 16 20:05:04.215 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"167peer2 # [38499.243895] peer2 systemd[1]: Reached target Host and Network Name Lookups.168peer2 # [38499.243971] peer2 systemd[1]: Reached target User and Group Name Lookups.169peer1 # [38499.200581] peer1 systemd[1]: Starting User Login Management...170peer1 # [38499.201476] peer1 systemd[1]: Starting Permit User Sessions...171peer1 # [38499.211636] peer1 systemd[1]: Finished Permit User Sessions.172peer1 # [38499.213554] peer1 systemd[1]: Started Console Getty.173peer1 # [38499.213629] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0174peer1 # [38499.213673] peer1 systemd[1]: Reached target Login Prompts.175peer2 # [38499.245415] peer2 systemd[1]: Starting User Login Management...176peer2 # [38499.246484] peer2 systemd[1]: Starting Permit User Sessions...177peer2 # [38499.258790] peer2 systemd[1]: Finished Permit User Sessions.178peer2 # [38499.259994] peer2 systemd[1]: Started Console Getty.179peer2 # [38499.260064] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0180peer2 # [38499.260091] peer2 systemd[1]: Reached target Login Prompts.181peer2 # [38499.473287] peer2 dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...182peer2 # [38499.474226] peer2 dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'183peer2 # [38499.474226] peer2 dbus-broker-launch[193]: Invalid user-name in /nix/store/4nf2hipj9b1rdkx13jln9fzw54jqlkpj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"184peer2 # [38499.474643] peer2 systemd[1]: Started D-Bus System Message Bus.185peer2 # [38499.483332] peer2 dbus-broker-launch[193]: Ready186peer1 # [38499.464266] peer1 dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...187peer1 # [38499.465792] peer1 dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'188peer1 # [38499.465792] peer1 dbus-broker-launch[193]: Invalid user-name in /nix/store/k78vvfbxpg7aakaa8z9kkmhbqkkgsips-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"189peer1 # [38499.466378] peer1 systemd[1]: Started D-Bus System Message Bus.190peer1 # [38499.474012] peer1 dbus-broker-launch[193]: Ready191peer2 # [38500.105970] peer2 systemd-logind[208]: New seat seat0.192peer2 # [38500.106220] peer2 systemd[1]: Started User Login Management.193peer2 # [38500.108387] peer2 systemd[1]: Starting linger-users.service...194peer2 # [38500.124351] peer2 systemd[1]: linger-users.service: Deactivated successfully.195peer2 # [38500.124527] peer2 systemd[1]: Finished linger-users.service.196peer2 # [38500.124943] peer2 systemd[1]: Reached target Multi-User System.197peer2 # [38500.144245] peer2 systemd[1]: Startup finished in 2.747s.198peer2 # [38500.284310] peer2 systemd-networkd[185]: eth1: Gained IPv6LL199peer1 # [38500.061566] peer1 systemd-logind[208]: New seat seat0.200peer1 # [38500.061770] peer1 systemd[1]: Started User Login Management.201peer1 # [38500.064087] peer1 systemd[1]: Starting linger-users.service...202peer1 # [38500.108896] peer1 systemd[1]: linger-users.service: Deactivated successfully.203peer1 # [38500.109084] peer1 systemd[1]: Finished linger-users.service.204peer1 # [38500.109587] peer1 systemd[1]: Reached target Multi-User System.205peer1 # [38500.124276] peer1 systemd[1]: Startup finished in 2.711s.206peer1: (finished: must succeed: greet-world, in 3.66 seconds)207peer2: must succeed: greet-world208peer2: (finished: must succeed: greet-world, in 0.01 seconds)209(finished: run the VM test script, in 3.68 seconds)210test script finished in 3.70s211cleanup212kill NspawnMachine (pid 52)213kill NspawnMachine (pid 53)214Container peer1 terminated by signal KILL.215Container peer2 terminated by signal KILL.216(finished: cleanup, in 0.38 seconds)