container-test-run-hello-service
default.checks.aarch64-linux.hello-service
· build #369
· 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)14peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...15peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...16(finished: start all VMs, in 0.00 seconds)17peer1: must succeed: greet-world18nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE19nixos-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.20nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.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 peer1 on /build/vm-state-peer1.25░ Spawning container peer2 on /build/vm-state-peer2.26peer1 # [5897143.295247] peer1 systemd-journald[69]: Journal started27peer1 # [5897143.295307] peer1 systemd-journald[69]: Runtime Journal (/run/log/journal/d5b1b23dae694acfaf67355712feedc1) is 8M, max 2.5G, 2.4G free.28peer1 # [5897143.304398] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...29peer1 # [5897143.305533] peer1 systemd[1]: Starting Network Name Resolution...30peer1 # [5897143.306456] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...31peer1 # [5897143.315678] peer1 systemd-journald[69]: Time spent on flushing to /var/log/journal/d5b1b23dae694acfaf67355712feedc1 is 938us for 5 entries.32peer1 # [5897143.315678] peer1 systemd-journald[69]: System Journal (/var/log/journal/d5b1b23dae694acfaf67355712feedc1) is 8M, max 4G, 3.9G free.33peer1 # [5897143.321393] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.34peer2 # [5897143.296978] peer2 systemd-journald[69]: Journal started35peer1 # [5897143.321615] peer1 systemd[1]: Reached target Preparation for Local File Systems.36peer2 # [5897143.297036] peer2 systemd-journald[69]: Runtime Journal (/run/log/journal/93628e65d3e843f3b77e1b495cdd0ed1) is 8M, max 2.5G, 2.4G free.37peer1 # [5897143.321693] peer1 systemd[1]: Reached target Local File Systems.38peer2 # [5897143.306216] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...39peer1 # [5897143.322416] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.40peer2 # [5897143.307097] peer2 systemd[1]: Starting Network Name Resolution...41peer1 # [5897143.322458] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container42peer2 # [5897143.307980] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...43peer1 # [5897143.323261] peer1 systemd[1]: Starting Save Transient machine-id to Disk...44peer2 # [5897143.315736] peer2 systemd-journald[69]: Time spent on flushing to /var/log/journal/93628e65d3e843f3b77e1b495cdd0ed1 is 962us for 5 entries.45peer1 # [5897143.323295] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys46peer2 # [5897143.315736] peer2 systemd-journald[69]: System Journal (/var/log/journal/93628e65d3e843f3b77e1b495cdd0ed1) is 8M, max 4G, 3.9G free.47peer1 # [5897143.362219] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.48peer2 # [5897143.323620] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.49peer1 # [5897143.364278] peer1 systemd[1]: Starting Create System Files and Directories...50peer2 # [5897143.323827] peer2 systemd[1]: Reached target Preparation for Local File Systems.51peer2 # [5897143.323904] peer2 systemd[1]: Reached target Local File Systems.52peer2 # [5897143.324618] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.53peer2 # [5897143.324662] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container54peer2 # [5897143.325434] peer2 systemd[1]: Starting Save Transient machine-id to Disk...55peer2 # [5897143.325470] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys56peer2 # [5897143.362161] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.57peer2 # [5897143.364452] peer2 systemd[1]: Starting Create System Files and Directories...58peer2 # [5897143.381877] peer2 systemd-tmpfiles[130]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted59peer2 # [5897143.382083] peer2 systemd-tmpfiles[130]: fchmod() of /var/log/journal failed: Operation not permitted60peer2 # [5897143.382227] peer2 systemd-tmpfiles[130]: fchmod() of /var/log/journal/93628e65d3e843f3b77e1b495cdd0ed1 failed: Operation not permitted61peer2 # [5897143.382445] peer2 systemd-tmpfiles[130]: fchmod() of /run/log/journal failed: Operation not permitted62peer1 # [5897143.383279] peer1 systemd-tmpfiles[131]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted63peer1 # [5897143.383510] peer1 systemd-tmpfiles[131]: fchmod() of /var/log/journal failed: Operation not permitted64peer1 # [5897143.383688] peer1 systemd-tmpfiles[131]: fchmod() of /var/log/journal/d5b1b23dae694acfaf67355712feedc1 failed: Operation not permitted65peer1 # [5897143.383936] peer1 systemd-tmpfiles[131]: fchmod() of /run/log/journal failed: Operation not permitted66peer1 # [5897143.385329] peer1 systemd[1]: Finished Create System Files and Directories.67peer1 # [5897143.386307] peer1 systemd[1]: Starting Rebuild Journal Catalog...68peer1 # [5897143.387029] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...69peer1 # [5897143.402978] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.70peer1 # [5897143.405968] peer1 systemd[1]: Finished Rebuild Journal Catalog.71peer1 # [5897143.407504] peer1 systemd[1]: Starting Update is Completed...72peer1 # [5897143.419776] peer1 systemd[1]: Finished Update is Completed.73peer1 # [5897143.443304] peer1 systemd[1]: Finished Firewall.74peer1 # [5897143.443471] peer1 systemd[1]: Reached target Preparation for Network.75peer1 # [5897143.443725] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.76peer1 # [5897143.444927] peer1 systemd[1]: Starting Network Management...77peer2 # [5897143.383738] peer2 systemd[1]: Finished Create System Files and Directories.78peer2 # [5897143.384812] peer2 systemd[1]: Starting Rebuild Journal Catalog...79peer2 # [5897143.385539] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...80peer2 # [5897143.402018] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.81peer2 # [5897143.408637] peer2 systemd[1]: Finished Rebuild Journal Catalog.82peer2 # [5897143.410317] peer2 systemd[1]: Starting Update is Completed...83peer2 # [5897143.421449] peer2 systemd[1]: Finished Update is Completed.84peer2 # [5897143.445371] peer2 systemd[1]: Finished Firewall.85peer2 # [5897143.445531] peer2 systemd[1]: Reached target Preparation for Network.86peer2 # [5897143.445774] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.87peer2 # [5897143.446856] peer2 systemd[1]: Starting Network Management...88peer2 # [5897143.786034] peer2 systemd-networkd[182]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted89peer2 # [5897143.786121] peer2 systemd-networkd[182]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted90peer2 # [5897143.792491] peer2 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.91peer2 # [5897143.792652] peer2 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.92peer2 # [5897143.792908] peer2 systemd-networkd[182]: lo: Link UP93peer2 # [5897143.792912] peer2 systemd-networkd[182]: lo: Gained carrier94peer2 # [5897143.793108] peer2 systemd-networkd[182]: eth1: Configuring with /etc/systemd/network/40-eth1.network.95peer2 # [5897143.793557] peer2 systemd[1]: Started Network Management.96peer2 # [5897143.793578] peer2 systemd-networkd[182]: eth1: Link UP97peer2 # [5897143.793896] peer2 systemd-networkd[182]: eth1: Gained carrier98peer2 # [5897143.795274] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...99peer2 # [5897143.918057] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.100peer2 # [5897143.930833] peer2 systemd-resolved[93]: Positive Trust Anchors:101peer2 # [5897143.930845] peer2 systemd-resolved[93]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d102peer2 # [5897143.930848] peer2 systemd-resolved[93]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16103peer2 # [5897143.930889] peer2 systemd-resolved[93]: 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 test104peer2 # [5897143.953224] peer2 systemd-resolved[93]: Using system hostname 'peer2'.105peer2 # [5897143.954679] peer2 systemd[1]: Started Network Name Resolution.106peer2 # [5897143.954819] peer2 systemd[1]: Reached target Network.107peer2 # [5897143.954942] peer2 systemd[1]: Reached target System Initialization.108peer2 # [5897143.955041] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container109peer2 # [5897143.955096] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.110peer2 # [5897143.955135] peer2 systemd[1]: Reached target Timer Units.111peer2 # [5897143.955375] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.112peer2 # [5897143.955592] peer2 systemd[1]: Listening on Nix Daemon Socket.113peer2 # [5897143.955827] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.114peer2 # [5897143.955874] peer2 systemd[1]: Reached target Socket Units.115peer2 # [5897143.955954] peer2 systemd[1]: Reached target Basic System.116peer2 # [5897143.957679] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...117peer2 # [5897143.959491] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...118peer2 # [5897143.961573] peer2 systemd[1]: Starting D-Bus System Message Bus...119peer2 # [5897143.980076] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.120peer1 # [5897143.789510] peer1 systemd-networkd[182]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted121peer1 # [5897143.789608] peer1 systemd-networkd[182]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted122peer1 # [5897143.795944] 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.123peer1 # [5897143.796116] 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.124peer1 # [5897143.796261] peer1 systemd-networkd[182]: lo: Link UP125peer1 # [5897143.796266] peer1 systemd-networkd[182]: lo: Gained carrier126peer1 # [5897143.796451] peer1 systemd-networkd[182]: eth1: Configuring with /etc/systemd/network/40-eth1.network.127peer1 # [5897143.796811] peer1 systemd[1]: Started Network Management.128peer1 # [5897143.796891] peer1 systemd-networkd[182]: eth1: Link UP129peer1 # [5897143.797098] peer1 systemd-networkd[182]: eth1: Gained carrier130peer1 # [5897143.797880] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...131peer1 # [5897143.917648] peer1 systemd-resolved[94]: Positive Trust Anchors:132peer1 # [5897143.917658] peer1 systemd-resolved[94]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d133peer1 # [5897143.917662] peer1 systemd-resolved[94]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16134peer1 # [5897143.917697] peer1 systemd-resolved[94]: 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 test135peer1 # [5897143.934765] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.136peer1 # [5897143.939885] peer1 systemd-resolved[94]: Using system hostname 'peer1'.137peer1 # [5897143.941269] peer1 systemd[1]: Started Network Name Resolution.138peer1 # [5897143.941392] peer1 systemd[1]: Reached target Network.139peer1 # [5897143.941505] peer1 systemd[1]: Reached target System Initialization.140peer1 # [5897143.941612] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container141peer1 # [5897143.941667] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.142peer1 # [5897143.941704] peer1 systemd[1]: Reached target Timer Units.143peer1 # [5897143.941927] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.144peer1 # [5897143.942145] peer1 systemd[1]: Listening on Nix Daemon Socket.145peer1 # [5897143.942357] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.146peer1 # [5897143.942407] peer1 systemd[1]: Reached target Socket Units.147peer1 # [5897143.942480] peer1 systemd[1]: Reached target Basic System.148peer1 # [5897143.944304] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...149peer1 # [5897143.945823] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...150peer1 # [5897143.948109] peer1 systemd[1]: Starting D-Bus System Message Bus...151peer1 # [5897143.965714] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.152peer1 # [5897144.099077] peer1 nsncd[188]: Aug 16 05:29:30.152 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"153peer2 # [5897144.078889] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).154peer1 # [5897144.099154] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).155peer1 # [5897144.099229] peer1 systemd[1]: Reached target Host and Network Name Lookups.156peer1 # [5897144.099303] peer1 systemd[1]: Reached target User and Group Name Lookups.157peer1 # [5897144.100623] peer1 systemd[1]: Starting User Login Management...158peer1 # [5897144.101659] peer1 systemd[1]: Starting Permit User Sessions...159peer1 # [5897144.111421] peer1 systemd[1]: Finished Permit User Sessions.160peer1 # [5897144.112436] peer1 systemd[1]: Started Console Getty.161peer1 # [5897144.112483] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0162peer1 # [5897144.112504] peer1 systemd[1]: Reached target Login Prompts.163peer1 # [5897144.217336] peer1 dbus-broker-launch[189]: Looking up NSS user entry for 'systemd-timesync'...164peer1 # [5897144.218090] peer1 dbus-broker-launch[189]: NSS returned no entry for 'systemd-timesync'165peer1 # [5897144.218090] peer1 dbus-broker-launch[189]: Invalid user-name in /nix/store/gqm83y9wavv3q3xqj6pj5s3vgjnv7cjw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"166peer1 # [5897144.218482] peer1 systemd[1]: Started D-Bus System Message Bus.167peer1 # [5897144.230689] peer1 dbus-broker-launch[189]: Ready168peer2 # [5897144.078958] peer2 systemd[1]: Reached target Host and Network Name Lookups.169peer2 # [5897144.079191] peer2 nsncd[188]: Aug 16 05:29:30.132 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"170peer2 # [5897144.079024] peer2 systemd[1]: Reached target User and Group Name Lookups.171peer2 # [5897144.080415] peer2 systemd[1]: Starting User Login Management...172peer2 # [5897144.081327] peer2 systemd[1]: Starting Permit User Sessions...173peer2 # [5897144.091245] peer2 systemd[1]: Finished Permit User Sessions.174peer2 # [5897144.093119] peer2 systemd[1]: Started Console Getty.175peer2 # [5897144.093174] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0176peer2 # [5897144.093196] peer2 systemd[1]: Reached target Login Prompts.177peer2 # [5897144.194680] peer2 dbus-broker-launch[189]: Looking up NSS user entry for 'systemd-timesync'...178peer2 # [5897144.195583] peer2 dbus-broker-launch[189]: NSS returned no entry for 'systemd-timesync'179peer2 # [5897144.195583] peer2 dbus-broker-launch[189]: Invalid user-name in /nix/store/a4aaq5wy7h462jpjy0dp7af0xld7rdv4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"180peer2 # [5897144.195994] peer2 systemd[1]: Started D-Bus System Message Bus.181peer2 # [5897144.203103] peer2 dbus-broker-launch[189]: Ready182peer2 # [5897144.585163] peer2 systemd-logind[204]: New seat seat0.183peer1 # [5897144.567210] peer1 systemd-logind[204]: New seat seat0.184peer2 # [5897144.585506] peer2 systemd[1]: Started User Login Management.185peer1 # [5897144.567647] peer1 systemd[1]: Started User Login Management.186peer2 # [5897144.586805] peer2 systemd[1]: Starting linger-users.service...187peer1 # [5897144.569250] peer1 systemd[1]: Starting linger-users.service...188peer2 # [5897144.600416] peer2 systemd[1]: linger-users.service: Deactivated successfully.189peer1 # [5897144.584031] peer1 systemd[1]: linger-users.service: Deactivated successfully.190peer2 # [5897144.600713] peer2 systemd[1]: Finished linger-users.service.191peer1 # [5897144.584342] peer1 systemd[1]: Finished linger-users.service.192peer2 # [5897144.601524] peer2 systemd[1]: Reached target Multi-User System.193peer1 # [5897144.585176] peer1 systemd[1]: Reached target Multi-User System.194peer2 # [5897144.928181] peer2 systemd-networkd[182]: eth1: Gained IPv6LL195peer2 # [5897145.060737] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.196peer2 # [5897145.061773] peer2 systemd[1]: Finished Save Transient machine-id to Disk.197peer2 # [5897145.061979] peer2 systemd[1]: Startup finished in 2.243s.198peer1 # [5897145.073639] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.199peer1 # [5897145.075818] peer1 systemd[1]: Finished Save Transient machine-id to Disk.200peer1 # [5897145.076242] peer1 systemd[1]: Startup finished in 2.246s.201peer1 # [5897145.184407] peer1 systemd-networkd[182]: eth1: Gained IPv6LL202peer1: (finished: must succeed: greet-world, in 3.15 seconds)203peer2: must succeed: greet-world204peer2: (finished: must succeed: greet-world, in 0.01 seconds)205(finished: run the VM test script, in 3.16 seconds)206test script finished in 3.75s207cleanup208kill NspawnMachine (pid 52)209kill NspawnMachine (pid 53)210Container peer1 terminated by signal KILL.211Container peer2 terminated by signal KILL.212(finished: cleanup, in 0.58 seconds)