container-test-run-hello-service
checks.aarch64-linux.hello-service
· build #641
· 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 peer1, peer2,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 VMs12peer1: systemd-nspawn running (pid 52)13peer2: systemd-nspawn running (pid 53)14peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...15peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...16(finished: start all VMs, in 0.00 seconds)17peer1: must succeed: greet-world18nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE19nixos-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.20nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.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.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.25░ Spawning container peer1 on /build/vm-state-peer1.26peer1 # No journal boot entry found for the specified boot (+0).27peer2 # No journal boot entry found for the specified boot (+0).28peer2 # [805539.156523] peer2 systemd-journald[72]: Journal started29peer2 # [805539.156585] peer2 systemd-journald[72]: Runtime Journal (/run/log/journal/aa7615ff4ca74dfea6f8566c08a08656) is 8M, max 2.5G, 2.4G free.30peer2 # [805539.169610] peer2 systemd[1]: Finished Apply Kernel Variables.31peer2 # [805539.184644] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.32peer2 # [805539.232474] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...33peer2 # [805539.233355] peer2 systemd[1]: Starting Network Name Resolution...34peer2 # [805539.234001] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...35peer2 # [805539.243270] peer2 systemd-journald[72]: Time spent on flushing to /var/log/journal/aa7615ff4ca74dfea6f8566c08a08656 is 1.765ms for 7 entries.36peer2 # [805539.243270] peer2 systemd-journald[72]: System Journal (/var/log/journal/aa7615ff4ca74dfea6f8566c08a08656) is 8M, max 4G, 3.9G free.37peer2 # [805539.272432] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.38peer2 # [805539.273327] peer2 systemd[1]: Reached target Preparation for Local File Systems.39peer2 # [805539.273504] peer2 systemd[1]: Reached target Local File Systems.40peer2 # [805539.274838] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.41peer2 # [805539.274917] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container42peer2 # [805539.276274] peer2 systemd[1]: Starting Save Transient machine-id to Disk...43peer2 # [805539.276325] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys44peer2 # [805539.292332] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.45peer2 # [805539.294512] peer2 systemd[1]: Starting Create System Files and Directories...46peer2 # [805539.311952] peer2 systemd-tmpfiles[160]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted47peer2 # [805539.312193] peer2 systemd-tmpfiles[160]: fchmod() of /var/log/journal failed: Operation not permitted48peer2 # [805539.312334] peer2 systemd-tmpfiles[160]: fchmod() of /var/log/journal/aa7615ff4ca74dfea6f8566c08a08656 failed: Operation not permitted49peer2 # [805539.312761] peer2 systemd-tmpfiles[160]: fchmod() of /run/log/journal failed: Operation not permitted50peer2 # [805539.314321] peer2 systemd[1]: Finished Create System Files and Directories.51peer2 # [805539.315356] peer2 systemd[1]: Starting Rebuild Journal Catalog...52peer2 # [805539.316083] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...53peer2 # [805539.329716] peer2 systemd[1]: Finished Firewall.54peer2 # [805539.330704] peer2 systemd[1]: Reached target Preparation for Network.55peer2 # [805539.331143] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.56peer2 # [805539.332391] peer2 systemd[1]: Starting Network Management...57peer2 # [805539.333903] peer2 systemd[1]: Finished Rebuild Journal Catalog.58peer2 # [805539.334103] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.59peer2 # [805539.336030] peer2 systemd[1]: Starting Update is Completed...60peer2 # [805539.346843] peer2 systemd[1]: Finished Update is Completed.61peer2 # [805539.374011] peer2 systemd[1]: Finished Save Transient machine-id to Disk.62peer2 # [805539.963241] peer2 systemd-networkd[181]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted63peer2 # [805539.963330] peer2 systemd-networkd[181]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted64peer2 # [805539.972346] peer2 systemd-networkd[181]: /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.65peer2 # [805539.972520] peer2 systemd-networkd[181]: /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.66peer2 # [805539.972678] peer2 systemd-networkd[181]: lo: Link UP67peer2 # [805539.972682] peer2 systemd-networkd[181]: lo: Gained carrier68peer2 # [805539.972876] peer2 systemd-networkd[181]: eth1: Configuring with /etc/systemd/network/40-eth1.network.69peer2 # [805539.973240] peer2 systemd[1]: Started Network Management.70peer1 # [805539.164192] peer1 systemd-journald[72]: Journal started71peer1 # [805539.164255] peer1 systemd-journald[72]: Runtime Journal (/run/log/journal/3a5d473020bc4e678d0379fd3a961875) is 8M, max 2.5G, 2.4G free.72peer1 # [805539.170773] peer1 systemd[1]: Finished Apply Kernel Variables.73peer2 # [805539.973334] peer2 systemd-networkd[181]: eth1: Link UP74peer1 # [805539.181850] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.75peer2 # [805539.973661] peer2 systemd-networkd[181]: eth1: Gained carrier76peer1 # [805539.195041] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...77peer2 # [805539.974486] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...78peer1 # [805539.196169] peer1 systemd[1]: Starting Network Name Resolution...79peer2 # [805540.010537] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.80peer1 # [805539.197081] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...81peer2 # [805540.162495] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.82peer1 # [805539.238861] peer1 systemd-journald[72]: Time spent on flushing to /var/log/journal/3a5d473020bc4e678d0379fd3a961875 is 1.781ms for 7 entries.83peer1 # [805539.238861] peer1 systemd-journald[72]: System Journal (/var/log/journal/3a5d473020bc4e678d0379fd3a961875) is 8M, max 4G, 3.9G free.84peer1 # [805539.251289] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.85peer1 # [805539.251936] peer1 systemd[1]: Reached target Preparation for Local File Systems.86peer1 # [805539.252076] peer1 systemd[1]: Reached target Local File Systems.87peer1 # [805539.252953] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.88peer1 # [805539.253001] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container89peer1 # [805539.253844] peer1 systemd[1]: Starting Save Transient machine-id to Disk...90peer1 # [805539.253875] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys91peer1 # [805539.294215] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.92peer1 # [805539.295307] peer1 systemd[1]: Starting Create System Files and Directories...93peer1 # [805539.310232] peer1 systemd-tmpfiles[157]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted94peer1 # [805539.310399] peer1 systemd-tmpfiles[157]: fchmod() of /var/log/journal failed: Operation not permitted95peer1 # [805539.310518] peer1 systemd-tmpfiles[157]: fchmod() of /var/log/journal/3a5d473020bc4e678d0379fd3a961875 failed: Operation not permitted96peer1 # [805539.310690] peer1 systemd-tmpfiles[157]: fchmod() of /run/log/journal failed: Operation not permitted97peer1 # [805539.312071] peer1 systemd[1]: Finished Create System Files and Directories.98peer1 # [805539.313268] peer1 systemd[1]: Starting Rebuild Journal Catalog...99peer1 # [805539.314112] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...100peer1 # [805539.326987] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.101peer1 # [805539.334383] peer1 systemd[1]: Finished Firewall.102peer1 # [805539.334534] peer1 systemd[1]: Reached target Preparation for Network.103peer1 # [805539.334758] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.104peer1 # [805539.335886] peer1 systemd[1]: Starting Network Management...105peer1 # [805539.337081] peer1 systemd[1]: Finished Rebuild Journal Catalog.106peer1 # [805539.338041] peer1 systemd[1]: Starting Update is Completed...107peer1 # [805539.347430] peer1 systemd[1]: Finished Update is Completed.108peer1 # [805539.373603] peer1 systemd[1]: Finished Save Transient machine-id to Disk.109peer1 # [805539.976370] peer1 systemd-networkd[182]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted110peer1 # [805539.976462] peer1 systemd-networkd[182]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted111peer1 # [805539.985721] peer1 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.112peer1 # [805539.985892] peer1 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.113peer1 # [805539.986050] peer1 systemd-networkd[182]: lo: Link UP114peer1 # [805539.986054] peer1 systemd-networkd[182]: lo: Gained carrier115peer1 # [805539.986248] peer1 systemd-networkd[182]: eth1: Configuring with /etc/systemd/network/40-eth1.network.116peer1 # [805539.986666] peer1 systemd[1]: Started Network Management.117peer1 # [805539.986739] peer1 systemd-networkd[182]: eth1: Link UP118peer1 # [805539.987048] peer1 systemd-networkd[182]: eth1: Gained carrier119peer1 # [805539.987899] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...120peer1 # [805540.026089] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.121peer1 # [805540.157332] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.122peer2 # [805540.318062] peer2 systemd-resolved[125]: Positive Trust Anchors:123peer2 # [805540.318073] peer2 systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d124peer2 # [805540.318077] peer2 systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16125peer2 # [805540.318112] peer2 systemd-resolved[125]: 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 test126peer2 # [805540.339882] peer2 systemd-resolved[125]: Using system hostname 'peer2'.127peer2 # [805540.341273] peer2 systemd[1]: Started Network Name Resolution.128peer2 # [805540.341370] peer2 systemd[1]: Reached target Network.129peer2 # [805540.341444] peer2 systemd[1]: Reached target System Initialization.130peer2 # [805540.341504] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container131peer2 # [805540.341538] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.132peer2 # [805540.341560] peer2 systemd[1]: Reached target Timer Units.133peer2 # [805540.341705] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.134peer2 # [805540.341843] peer2 systemd[1]: Listening on Nix Daemon Socket.135peer2 # [805540.341972] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.136peer2 # [805540.341999] peer2 systemd[1]: Reached target Socket Units.137peer2 # [805540.342052] peer2 systemd[1]: Reached target Basic System.138peer2 # [805540.343663] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...139peer2 # [805540.344653] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...140peer2 # [805540.346200] peer2 systemd[1]: Starting D-Bus System Message Bus...141peer2 # [805540.363310] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.142peer2 # [805540.506763] peer2 nsncd[192]: Sep 13 15:12:40.492 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"143peer2 # [805540.506792] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).144peer2 # [805540.506856] peer2 systemd[1]: Reached target Host and Network Name Lookups.145peer2 # [805540.506917] peer2 systemd[1]: Reached target User and Group Name Lookups.146peer2 # [805540.508137] peer2 systemd[1]: Starting User Login Management...147peer2 # [805540.509104] peer2 systemd[1]: Starting Permit User Sessions...148peer2 # [805540.518744] peer2 systemd[1]: Finished Permit User Sessions.149peer2 # [805540.519907] peer2 systemd[1]: Started Console Getty.150peer2 # [805540.519952] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0151peer2 # [805540.519972] peer2 systemd[1]: Reached target Login Prompts.152peer2 # [805540.667312] peer2 dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...153peer1 # [805540.323092] peer1 systemd-resolved[102]: Positive Trust Anchors:154peer1 # [805540.323103] peer1 systemd-resolved[102]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d155peer1 # [805540.323106] peer1 systemd-resolved[102]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16156peer1 # [805540.323140] peer1 systemd-resolved[102]: 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 test157peer1 # [805540.345409] peer1 systemd-resolved[102]: Using system hostname 'peer1'.158peer1 # [805540.346804] peer1 systemd[1]: Started Network Name Resolution.159peer1 # [805540.346938] peer1 systemd[1]: Reached target Network.160peer1 # [805540.347056] peer1 systemd[1]: Reached target System Initialization.161peer1 # [805540.347153] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container162peer1 # [805540.347206] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.163peer1 # [805540.347243] peer1 systemd[1]: Reached target Timer Units.164peer1 # [805540.347464] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.165peer1 # [805540.347680] peer1 systemd[1]: Listening on Nix Daemon Socket.166peer1 # [805540.347903] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.167peer1 # [805540.347949] peer1 systemd[1]: Reached target Socket Units.168peer1 # [805540.348059] peer1 systemd[1]: Reached target Basic System.169peer1 # [805540.350237] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...170peer1 # [805540.351743] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...171peer1 # [805540.353736] peer1 systemd[1]: Starting D-Bus System Message Bus...172peer1 # [805540.369763] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.173peer1 # [805540.510562] peer1 nsncd[192]: Sep 13 15:12:40.496 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"174peer1 # [805540.510628] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).175peer1 # [805540.510690] peer1 systemd[1]: Reached target Host and Network Name Lookups.176peer1 # [805540.510749] peer1 systemd[1]: Reached target User and Group Name Lookups.177peer1 # [805540.511900] peer1 systemd[1]: Starting User Login Management...178peer1 # [805540.512673] peer1 systemd[1]: Starting Permit User Sessions...179peer1 # [805540.522633] peer1 systemd[1]: Finished Permit User Sessions.180peer1 # [805540.523665] peer1 systemd[1]: Started Console Getty.181peer1 # [805540.523722] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0182peer1 # [805540.523743] peer1 systemd[1]: Reached target Login Prompts.183peer1 # [805540.674645] peer1 dbus-broker-launch[193]: Looking up NSS user entry for 'systemd-timesync'...184peer1 # [805540.675801] peer1 dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'185peer1 # [805540.675801] peer1 dbus-broker-launch[193]: Invalid user-name in /nix/store/9yyzw8w7lnh60nkshc4q1gjybvw46dvd-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"186peer1 # [805540.676256] peer1 systemd[1]: Started D-Bus System Message Bus.187peer1 # [805540.685144] peer1 dbus-broker-launch[193]: Ready188peer2 # [805540.668437] peer2 dbus-broker-launch[193]: NSS returned no entry for 'systemd-timesync'189peer2 # [805540.668437] peer2 dbus-broker-launch[193]: Invalid user-name in /nix/store/cvn661n5iv45sscw185xa46yhc21ss29-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"190peer2 # [805540.669154] peer2 systemd[1]: Started D-Bus System Message Bus.191peer2 # [805540.676907] peer2 dbus-broker-launch[193]: Ready192peer1: (finished: must succeed: greet-world, in 3.16 seconds)193peer2: must succeed: greet-world194peer2: (finished: must succeed: greet-world, in 0.01 seconds)195(finished: run the VM test script, in 3.17 seconds)196peer2 # [805541.283781] peer2 systemd-logind[208]: New seat seat0.197peer2 # [805541.283986] peer2 systemd[1]: Started User Login Management.198peer1 # [805541.216397] peer1 systemd-networkd[182]: eth1: Gained IPv6LL199peer2 # [805541.286309] peer2 systemd[1]: Starting linger-users.service...200peer1 # [805541.299970] peer1 systemd-logind[208]: New seat seat0.201peer2 # [805541.339793] peer2 systemd[1]: linger-users.service: Deactivated successfully.202peer1 # [805541.300272] peer1 systemd[1]: Started User Login Management.203peer2 # [805541.340057] peer2 systemd[1]: Finished linger-users.service.204peer1 # [805541.328619] peer1 systemd[1]: Starting linger-users.service...205peer2 # [805541.341009] peer2 systemd[1]: Reached target Multi-User System.206peer1 # [805541.342860] peer1 systemd[1]: linger-users.service: Deactivated successfully.207peer2 # [805541.341340] peer2 systemd[1]: Startup finished in 2.640s.208peer1 # [805541.342967] peer1 systemd[1]: Finished linger-users.service.209peer2 # [805541.348204] peer2 systemd-networkd[181]: eth1: Gained IPv6LL210peer1 # [805541.343467] peer1 systemd[1]: Reached target Multi-User System.211peer1 # [805541.352255] peer1 systemd[1]: Startup finished in 2.644s.212test script finished in 3.35s213cleanup214kill NspawnMachine (pid 52)215kill NspawnMachine (pid 53)216Container peer1 terminated by signal KILL.217Container peer2 terminated by signal KILL.218(finished: cleanup, in 0.38 seconds)