container-test-run-service-dummy-test-from-flake
checks.aarch64-linux.service-dummy-test-from-flake
· build #662
· 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 admin1, peer1,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 VMs12admin1: systemd-nspawn running (pid 52)13peer1: systemd-nspawn running (pid 53)14admin1: Waiting for journal at /build/vm-state-admin1/var/log/journal...15peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...16(finished: start all VMs, in 0.00 seconds)17admin1: waiting for unit multi-user.target18nixos-nspawn(admin1): TAP vde-tap1 not found; container will be isolated from VDE19nixos-nspawn(admin1): 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 admin1 on /build/vm-state-admin1.25░ Spawning container peer1 on /build/vm-state-peer1.26admin1 # No journal files were found.27peer1 # No journal files were found.28admin1 # No journal boot entry found for the specified boot (+0).29peer1 # No journal boot entry found for the specified boot (+0).30admin1 # No journal boot entry found for the specified boot (+0).31peer1 # No journal boot entry found for the specified boot (+0).32peer1 # [976237.984825] peer1 systemd-journald[78]: Journal started33peer1 # [976237.984880] peer1 systemd-journald[78]: Runtime Journal (/run/log/journal/3ece72a1b52c45d6be65ecec89fcfcc4) is 8M, max 2.5G, 2.4G free.34peer1 # [976238.007955] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.35peer1 # [976238.016534] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...36peer1 # [976238.017388] peer1 systemd[1]: Starting Network Name Resolution...37peer1 # [976238.018104] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...38peer1 # [976238.061832] peer1 systemd-journald[78]: Time spent on flushing to /var/log/journal/3ece72a1b52c45d6be65ecec89fcfcc4 is 1.318ms for 6 entries.39peer1 # [976238.061832] peer1 systemd-journald[78]: System Journal (/var/log/journal/3ece72a1b52c45d6be65ecec89fcfcc4) is 8M, max 4G, 3.9G free.40peer1 # [976238.067404] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.41peer1 # [976238.068075] peer1 systemd[1]: Reached target Preparation for Local File Systems.42peer1 # [976238.068195] peer1 systemd[1]: Reached target Local File Systems.43peer1 # [976238.069028] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.44peer1 # [976238.069074] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container45peer1 # [976238.069957] peer1 systemd[1]: Starting Save Transient machine-id to Disk...46peer1 # [976238.069989] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys47peer1 # [976238.139349] peer1 systemd[1]: Finished Firewall.48peer1 # [976238.140088] peer1 systemd[1]: Reached target Preparation for Network.49peer1 # [976238.140482] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.50peer1 # [976238.141955] peer1 systemd[1]: Starting Network Management...51peer1 # [976238.427381] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.52peer1 # [976238.428922] peer1 systemd[1]: Starting Create System Files and Directories...53peer1 # [976238.525599] peer1 systemd-networkd[181]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted54peer1 # [976238.525692] peer1 systemd-networkd[181]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted55peer1 # [976238.532489] peer1 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.56peer1 # [976238.532652] peer1 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.57peer1 # [976238.532813] peer1 systemd-networkd[181]: lo: Link UP58peer1 # [976238.532816] peer1 systemd-networkd[181]: lo: Gained carrier59peer1 # [976238.533005] peer1 systemd-networkd[181]: eth1: Configuring with /etc/systemd/network/40-eth1.network.60peer1 # [976238.533391] peer1 systemd[1]: Started Network Management.61peer1 # [976238.533497] peer1 systemd-networkd[181]: eth1: Link UP62peer1 # [976238.533865] peer1 systemd-networkd[181]: eth1: Gained carrier63peer1 # [976238.534405] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...64peer1 # [976238.685821] peer1 systemd-resolved[111]: Positive Trust Anchors:65peer1 # [976238.685830] peer1 systemd-resolved[111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d66peer1 # [976238.685832] peer1 systemd-resolved[111]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b1667peer1 # [976238.685869] peer1 systemd-resolved[111]: 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 test68peer1 # [976238.707883] peer1 systemd-resolved[111]: Using system hostname 'peer1'.69peer1 # [976238.709236] peer1 systemd[1]: Started Network Name Resolution.70peer1 # [976238.709315] peer1 systemd[1]: Reached target Network.71peer1 # [976238.760812] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.72peer1 # [976238.763444] peer1 systemd-tmpfiles[185]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted73peer1 # [976238.763710] peer1 systemd-tmpfiles[185]: fchmod() of /var/log/journal failed: Operation not permitted74peer1 # [976238.763887] peer1 systemd-tmpfiles[185]: fchmod() of /var/log/journal/3ece72a1b52c45d6be65ecec89fcfcc4 failed: Operation not permitted75peer1 # [976238.764663] peer1 systemd-tmpfiles[185]: fchmod() of /run/log/journal failed: Operation not permitted76peer1 # [976238.769114] peer1 systemd[1]: Finished Create System Files and Directories.77peer1 # [976238.771266] peer1 systemd[1]: Starting Rebuild Journal Catalog...78peer1 # [976238.773906] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...79peer1 # [976238.841774] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.80peer1 # [976238.850653] peer1 systemd[1]: Finished Rebuild Journal Catalog.81peer1 # [976238.852662] peer1 systemd[1]: Starting Update is Completed...82peer1 # [976238.865001] peer1 systemd[1]: Finished Update is Completed.83peer1 # [976238.865203] peer1 systemd[1]: Reached target System Initialization.84peer1 # [976238.865292] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container85peer1 # [976238.865339] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.86peer1 # [976238.865370] peer1 systemd[1]: Reached target Timer Units.87peer1 # [976238.865516] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.88peer1 # [976238.865668] peer1 systemd[1]: Listening on Nix Daemon Socket.89peer1 # [976238.865811] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.90peer1 # [976238.865838] peer1 systemd[1]: Reached target Socket Units.91peer1 # [976238.865889] peer1 systemd[1]: Reached target Basic System.92peer1 # [976238.867302] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...93peer1 # [976238.868411] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...94peer1 # [976238.869948] peer1 systemd[1]: Starting D-Bus System Message Bus...95peer1 # [976238.891084] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.96peer1 # [976238.981788] peer1 nsncd[197]: Sep 15 14:37:38.967 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"97peer1 # [976238.981847] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).98peer1 # [976238.981949] peer1 systemd[1]: Reached target Host and Network Name Lookups.99peer1 # [976238.982052] peer1 systemd[1]: Reached target User and Group Name Lookups.100peer1 # [976238.984247] peer1 systemd[1]: Starting User Login Management...101peer1 # [976238.985484] peer1 systemd[1]: Starting Permit User Sessions...102peer1 # [976239.027769] peer1 systemd[1]: Finished Permit User Sessions.103peer1 # [976239.028865] peer1 systemd[1]: Started Console Getty.104peer1 # [976239.028906] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0105peer1 # [976239.028926] peer1 systemd[1]: Reached target Login Prompts.106peer1 # [976239.067713] peer1 dbus-broker-launch[198]: Looking up NSS user entry for 'systemd-timesync'...107peer1 # [976239.068882] peer1 dbus-broker-launch[198]: NSS returned no entry for 'systemd-timesync'108peer1 # [976239.068882] peer1 dbus-broker-launch[198]: Invalid user-name in /nix/store/5xsyadnlhlwnyvziab02f3n7ak7gk87b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"109peer1 # [976239.069618] peer1 systemd[1]: Started D-Bus System Message Bus.110peer1 # [976239.077551] peer1 dbus-broker-launch[198]: Ready111admin1 # [976237.994218] admin1 systemd-journald[69]: Journal started112admin1 # [976237.994268] admin1 systemd-journald[69]: Runtime Journal (/run/log/journal/4ff98ae689594e64912a07bf8960ad07) is 8M, max 2.5G, 2.4G free.113admin1 # [976238.009858] admin1 systemd[1]: Finished Apply Kernel Variables.114admin1 # [976238.016179] admin1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.115admin1 # [976238.056463] admin1 systemd[1]: Starting Flush Journal to Persistent Storage...116admin1 # [976238.057546] admin1 systemd[1]: Starting Network Name Resolution...117admin1 # [976238.058207] admin1 systemd[1]: Starting Create Static Device Nodes in /dev...118admin1 # [976238.065862] admin1 systemd-journald[69]: Time spent on flushing to /var/log/journal/4ff98ae689594e64912a07bf8960ad07 is 9.101ms for 7 entries.119admin1 # [976238.065862] admin1 systemd-journald[69]: System Journal (/var/log/journal/4ff98ae689594e64912a07bf8960ad07) is 8M, max 4G, 3.9G free.120admin1 # [976238.072719] admin1 systemd[1]: Finished Create Static Device Nodes in /dev.121admin1 # [976238.072968] admin1 systemd[1]: Reached target Preparation for Local File Systems.122admin1 # [976238.073057] admin1 systemd[1]: Reached target Local File Systems.123admin1 # [976238.073791] admin1 systemd[1]: Listening on Boot Loader Control Service Socket.124admin1 # [976238.073835] admin1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container125admin1 # [976238.074618] admin1 systemd[1]: Starting Save Transient machine-id to Disk...126admin1 # [976238.074651] admin1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys127admin1 # [976238.180292] admin1 systemd[1]: Finished Firewall.128admin1 # [976238.180500] admin1 systemd[1]: Reached target Preparation for Network.129admin1 # [976238.180740] admin1 systemd[1]: Listening on Network Management Resolve Hook Socket.130admin1 # [976238.181972] admin1 systemd[1]: Starting Network Management...131admin1 # [976238.552284] admin1 systemd-networkd[172]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted132admin1 # [976238.552373] admin1 systemd-networkd[172]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted133admin1 # [976238.559045] admin1 systemd-networkd[172]: /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.134admin1 # [976238.559208] admin1 systemd-networkd[172]: /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.135admin1 # [976238.559362] admin1 systemd-networkd[172]: lo: Link UP136admin1 # [976238.559366] admin1 systemd-networkd[172]: lo: Gained carrier137admin1 # [976238.559544] admin1 systemd-networkd[172]: eth1: Configuring with /etc/systemd/network/40-eth1.network.138admin1 # [976238.559947] admin1 systemd[1]: Started Network Management.139admin1 # [976238.560023] admin1 systemd-networkd[172]: eth1: Link UP140admin1 # [976238.560342] admin1 systemd-networkd[172]: eth1: Gained carrier141admin1 # [976238.561162] admin1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...142admin1 # [976238.673962] admin1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.143admin1 # [976238.697546] admin1 systemd-resolved[119]: Positive Trust Anchors:144admin1 # [976238.697554] admin1 systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d145admin1 # [976238.697556] admin1 systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16146admin1 # [976238.697591] admin1 systemd-resolved[119]: 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 test147admin1 # [976238.719251] admin1 systemd-resolved[119]: Using system hostname 'admin1'.148admin1 # [976238.720508] admin1 systemd[1]: Started Network Name Resolution.149admin1 # [976238.720573] admin1 systemd[1]: Reached target Network.150admin1 # [976238.828256] admin1 systemd[1]: Finished Flush Journal to Persistent Storage.151admin1 # [976238.829939] admin1 systemd[1]: Starting Create System Files and Directories...152admin1 # [976238.847920] admin1 systemd-tmpfiles[179]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted153admin1 # [976238.848350] admin1 systemd-tmpfiles[179]: fchmod() of /var/log/journal failed: Operation not permitted154admin1 # [976238.848509] admin1 systemd-tmpfiles[179]: fchmod() of /var/log/journal/4ff98ae689594e64912a07bf8960ad07 failed: Operation not permitted155admin1 # [976238.848737] admin1 systemd-tmpfiles[179]: fchmod() of /run/log/journal failed: Operation not permitted156admin1 # [976238.852818] admin1 systemd[1]: Finished Create System Files and Directories.157admin1 # [976238.854153] admin1 systemd[1]: Starting Rebuild Journal Catalog...158admin1 # [976238.855117] admin1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...159admin1 # [976238.871910] admin1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.160admin1 # [976238.878301] admin1 systemd[1]: Finished Rebuild Journal Catalog.161admin1 # [976238.879606] admin1 systemd[1]: Starting Update is Completed...162admin1 # [976238.891697] admin1 systemd[1]: Finished Update is Completed.163admin1 # [976238.891996] admin1 systemd[1]: Reached target System Initialization.164admin1 # [976238.892174] admin1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container165admin1 # [976238.892236] admin1 systemd[1]: Started Daily Cleanup of Temporary Directories.166admin1 # [976238.892280] admin1 systemd[1]: Reached target Timer Units.167admin1 # [976238.892522] admin1 systemd[1]: Listening on D-Bus System Message Bus Socket.168admin1 # [976238.892765] admin1 systemd[1]: Listening on Nix Daemon Socket.169admin1 # [976238.893007] admin1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.170admin1 # [976238.893060] admin1 systemd[1]: Reached target Socket Units.171admin1 # [976238.893143] admin1 systemd[1]: Reached target Basic System.172admin1 # [976238.895062] admin1 systemd[1]: Starting Import lastlog data into lastlog2 database...173admin1 # [976238.896476] admin1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...174admin1 # [976238.898611] admin1 systemd[1]: Starting D-Bus System Message Bus...175admin1 # [976238.917086] admin1 systemd[1]: Finished Import lastlog data into lastlog2 database.176admin1 # [976238.996243] admin1 nsncd[188]: Sep 15 14:37:38.982 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"177admin1 # [976238.996314] admin1 systemd[1]: Started Name Service Cache Daemon (nsncd).178admin1 # [976238.996376] admin1 systemd[1]: Reached target Host and Network Name Lookups.179admin1 # [976238.996436] admin1 systemd[1]: Reached target User and Group Name Lookups.180admin1 # [976239.020482] admin1 systemd[1]: Starting User Login Management...181admin1 # [976239.021618] admin1 systemd[1]: Starting Permit User Sessions...182admin1 # [976239.033359] admin1 systemd[1]: Finished Permit User Sessions.183admin1 # [976239.034999] admin1 systemd[1]: Started Console Getty.184admin1 # [976239.035078] admin1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0185admin1 # [976239.035121] admin1 systemd[1]: Reached target Login Prompts.186admin1 # [976239.075183] admin1 dbus-broker-launch[189]: Looking up NSS user entry for 'systemd-timesync'...187admin1 # [976239.076538] admin1 dbus-broker-launch[189]: NSS returned no entry for 'systemd-timesync'188admin1 # [976239.076538] admin1 dbus-broker-launch[189]: Invalid user-name in /nix/store/5xsyadnlhlwnyvziab02f3n7ak7gk87b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"189admin1 # [976239.077089] admin1 systemd[1]: Started D-Bus System Message Bus.190admin1 # [976239.084667] admin1 dbus-broker-launch[189]: Ready191peer1 # [976239.511686] peer1 systemd-logind[213]: New seat seat0.192admin1 # [976239.537045] admin1 systemd-logind[204]: New seat seat0.193peer1 # [976239.511882] peer1 systemd[1]: Started User Login Management.194admin1 # [976239.537223] admin1 systemd[1]: Started User Login Management.195peer1 # [976239.513258] peer1 systemd[1]: Starting linger-users.service...196admin1 # [976239.556695] admin1 systemd[1]: Starting linger-users.service...197admin1 # [976239.574527] admin1 systemd[1]: linger-users.service: Deactivated successfully.198peer1 # [976239.566521] peer1 systemd[1]: linger-users.service: Deactivated successfully.199admin1 # [976239.574612] admin1 systemd[1]: Finished linger-users.service.200peer1 # [976239.566952] peer1 systemd[1]: Finished linger-users.service.201admin1 # [976239.575098] admin1 systemd[1]: Reached target Multi-User System.202peer1 # [976239.567408] peer1 systemd[1]: Reached target Multi-User System.203admin1 # [976240.064204] admin1 systemd-networkd[172]: eth1: Gained IPv6LL204peer1 # [976240.320219] peer1 systemd-networkd[181]: eth1: Gained IPv6LL205admin1: still waiting for container 'admin1' to reach ready state...206admin1: still waiting for container 'admin1' to reach ready state...207admin1: still waiting for container 'admin1' to reach ready state...208admin1: still waiting for container 'admin1' to reach ready state...209admin1: still waiting for container 'admin1' to reach ready state...210admin1: still waiting for container 'admin1' to reach ready state...211admin1: still waiting for container 'admin1' to reach ready state...212admin1: still waiting for container 'admin1' to reach ready state...213admin1: still waiting for container 'admin1' to reach ready state...214admin1 # [976328.105148] admin1 systemd[1]: systemd-machine-id-commit.service: start operation timed out. Terminating.215peer1 # [976328.113574] peer1 systemd[1]: systemd-machine-id-commit.service: start operation timed out. Terminating.216admin1: still waiting for container 'admin1' to reach ready state...217admin1: still waiting for container 'admin1' to reach ready state...218admin1: still waiting for container 'admin1' to reach ready state...219admin1: still waiting for container 'admin1' to reach ready state...220admin1: still waiting for container 'admin1' to reach ready state...221admin1: still waiting for container 'admin1' to reach ready state...222admin1: still waiting for container 'admin1' to reach ready state...223admin1: still waiting for container 'admin1' to reach ready state...224admin1: still waiting for container 'admin1' to reach ready state...225admin1 # [976418.355034] admin1 systemd[1]: systemd-machine-id-commit.service: State 'stop-sigterm' timed out. Killing.226admin1 # [976418.355147] admin1 systemd[1]: systemd-machine-id-commit.service: Killing process 131 (systemd-machine) with signal SIGKILL.227peer1 # [976418.363468] peer1 systemd[1]: systemd-machine-id-commit.service: State 'stop-sigterm' timed out. Killing.228peer1 # [976418.363570] peer1 systemd[1]: systemd-machine-id-commit.service: Killing process 142 (systemd-machine) with signal SIGKILL.229admin1: still waiting for container 'admin1' to reach ready state...230admin1: still waiting for container 'admin1' to reach ready state...231admin1: still waiting for container 'admin1' to reach ready state...232admin1: still waiting for container 'admin1' to reach ready state...233admin1: still waiting for container 'admin1' to reach ready state...234admin1: still waiting for container 'admin1' to reach ready state...235admin1: still waiting for container 'admin1' to reach ready state...236admin1: still waiting for container 'admin1' to reach ready state...237admin1: still waiting for container 'admin1' to reach ready state...238admin1 # [976508.605030] admin1 systemd[1]: systemd-machine-id-commit.service: Processes still around after SIGKILL. Ignoring.239peer1 # [976508.613460] peer1 systemd[1]: systemd-machine-id-commit.service: Processes still around after SIGKILL. Ignoring.240admin1: still waiting for container 'admin1' to reach ready state...241admin1: still waiting for container 'admin1' to reach ready state...242admin1: still waiting for container 'admin1' to reach ready state...243admin1: still waiting for container 'admin1' to reach ready state...244admin1: still waiting for container 'admin1' to reach ready state...245admin1: still waiting for container 'admin1' to reach ready state...246admin1: still waiting for container 'admin1' to reach ready state...247admin1: still waiting for container 'admin1' to reach ready state...248admin1: still waiting for container 'admin1' to reach ready state...249admin1 # [976598.855015] admin1 systemd[1]: systemd-machine-id-commit.service: State 'final-sigterm' timed out. Killing.250admin1 # [976598.855109] admin1 systemd[1]: systemd-machine-id-commit.service: Killing process 131 (systemd-machine) with signal SIGKILL.251peer1 # [976598.863473] peer1 systemd[1]: systemd-machine-id-commit.service: State 'final-sigterm' timed out. Killing.252peer1 # [976598.863564] peer1 systemd[1]: systemd-machine-id-commit.service: Killing process 142 (systemd-machine) with signal SIGKILL.253admin1: still waiting for container 'admin1' to reach ready state...254admin1: still waiting for container 'admin1' to reach ready state...255admin1: still waiting for container 'admin1' to reach ready state...256admin1: still waiting for container 'admin1' to reach ready state...257admin1: still waiting for container 'admin1' to reach ready state...258admin1: still waiting for container 'admin1' to reach ready state...259admin1: still waiting for container 'admin1' to reach ready state...260admin1: still waiting for container 'admin1' to reach ready state...261admin1: still waiting for container 'admin1' to reach ready state...262peer1 # [976689.113443] peer1 systemd[1]: systemd-machine-id-commit.service: Processes still around after final SIGKILL. Entering failed mode.263peer1 # [976689.113462] peer1 systemd[1]: systemd-machine-id-commit.service: Failed with result 'timeout'.264peer1 # [976689.113615] peer1 systemd[1]: systemd-machine-id-commit.service: Unit process 142 (systemd-machine) remains running after unit stopped.265peer1 # [976689.113917] peer1 systemd[1]: Failed to start Save Transient machine-id to Disk.266peer1 # [976689.114863] peer1 systemd[1]: Startup finished in 7min 32.222s.267peer1 # [976689.114970] peer1 systemd[1]: systemd-machine-id-commit.service: Consumed 1.635s CPU time over 7min 31.043s wall clock time.268admin1 # [976689.104995] admin1 systemd[1]: systemd-machine-id-commit.service: Processes still around after final SIGKILL. Entering failed mode.269admin1 # [976689.105011] admin1 systemd[1]: systemd-machine-id-commit.service: Failed with result 'timeout'.270admin1 # [976689.105172] admin1 systemd[1]: systemd-machine-id-commit.service: Unit process 131 (systemd-machine) remains running after unit stopped.271admin1 # [976689.105669] admin1 systemd[1]: Failed to start Save Transient machine-id to Disk.272admin1 # [976689.106277] admin1 systemd[1]: Startup finished in 7min 32.218s.273admin1 # [976689.106387] admin1 systemd[1]: systemd-machine-id-commit.service: Consumed 1.635s CPU time over 7min 31.030s wall clock time.274admin1: (finished: waiting for unit multi-user.target, in 453.32 seconds)275peer1: waiting for unit multi-user.target276peer1: (finished: waiting for unit multi-user.target, in 0.02 seconds)277peer1: must succeed: cat /nix/store/z61c0lalzvfahapklpif5sc0cd4x5g4b-per-machine-peer1-new-service_not-a-secret278peer1: (finished: must succeed: cat /nix/store/z61c0lalzvfahapklpif5sc0cd4x5g4b-per-machine-peer1-new-service_not-a-secret, in 0.01 seconds)279peer1: must succeed: ls -la /run/secrets/per-machine/peer1/new-service/a-secret280peer1: (finished: must succeed: ls -la /run/secrets/per-machine/peer1/new-service/a-secret, in 0.01 seconds)281admin1282peer1283(finished: run the VM test script, in 471.47 seconds)284test script finished in 735.44s285cleanup286kill NspawnMachine (pid 52)287kill NspawnMachine (pid 53)288Container admin1 terminated by signal KILL.289Container peer1 terminated by signal KILL.290(finished: cleanup, in 0.68 seconds)