container-test-run-service-dummy-test-from-flake
checks.aarch64-linux.service-dummy-test-from-flake
· build #661
· 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(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(admin1): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.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 admin1 on /build/vm-state-admin1.26admin1 # No journal boot entry found for the specified boot (+0).27peer1 # No journal boot entry found for the specified boot (+0).28admin1 # [976158.733507] admin1 systemd-journald[69]: Journal started29admin1 # [976158.733560] admin1 systemd-journald[69]: Runtime Journal (/run/log/journal/6d281d13539c455f9d15d93cebc45562) is 8M, max 2.5G, 2.4G free.30admin1 # [976158.742931] admin1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.31admin1 # [976158.769984] admin1 systemd[1]: Starting Flush Journal to Persistent Storage...32admin1 # [976158.771240] admin1 systemd[1]: Starting Network Name Resolution...33admin1 # [976158.774371] admin1 systemd[1]: Starting Create Static Device Nodes in /dev...34admin1 # [976158.781753] admin1 systemd-journald[69]: Time spent on flushing to /var/log/journal/6d281d13539c455f9d15d93cebc45562 is 1.659ms for 6 entries.35admin1 # [976158.781753] admin1 systemd-journald[69]: System Journal (/var/log/journal/6d281d13539c455f9d15d93cebc45562) is 8M, max 4G, 3.9G free.36admin1 # [976158.817020] admin1 systemd[1]: Finished Create Static Device Nodes in /dev.37admin1 # [976158.818735] admin1 systemd[1]: Reached target Preparation for Local File Systems.38admin1 # [976158.818857] admin1 systemd[1]: Reached target Local File Systems.39admin1 # [976158.819733] admin1 systemd[1]: Listening on Boot Loader Control Service Socket.40admin1 # [976158.819784] admin1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container41admin1 # [976158.821009] admin1 systemd[1]: Starting Save Transient machine-id to Disk...42admin1 # [976158.821042] admin1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys43admin1 # [976158.893716] admin1 systemd[1]: Finished Firewall.44admin1 # [976158.893889] admin1 systemd[1]: Reached target Preparation for Network.45admin1 # [976158.894126] admin1 systemd[1]: Listening on Network Management Resolve Hook Socket.46admin1 # [976158.895249] admin1 systemd[1]: Starting Network Management...47admin1 # [976158.940818] admin1 systemd[1]: Finished Flush Journal to Persistent Storage.48admin1 # [976158.942459] admin1 systemd[1]: Starting Create System Files and Directories...49admin1 # [976158.959943] admin1 systemd-tmpfiles[176]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted50admin1 # [976158.960529] admin1 systemd-tmpfiles[176]: fchmod() of /var/log/journal failed: Operation not permitted51admin1 # [976158.960649] admin1 systemd-tmpfiles[176]: fchmod() of /var/log/journal/6d281d13539c455f9d15d93cebc45562 failed: Operation not permitted52admin1 # [976158.960834] admin1 systemd-tmpfiles[176]: fchmod() of /run/log/journal failed: Operation not permitted53admin1 # [976158.962619] admin1 systemd[1]: Finished Create System Files and Directories.54admin1 # [976158.964336] admin1 systemd[1]: Starting Rebuild Journal Catalog...55admin1 # [976158.965915] admin1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...56admin1 # [976158.981460] admin1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.57admin1 # [976158.989953] admin1 systemd[1]: Finished Rebuild Journal Catalog.58admin1 # [976158.991170] admin1 systemd[1]: Starting Update is Completed...59admin1 # [976159.002524] admin1 systemd[1]: Finished Update is Completed.60admin1 # [976159.528919] admin1 systemd-networkd[172]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted61admin1 # [976159.529063] admin1 systemd-networkd[172]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted62admin1 # [976159.536271] 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.63admin1 # [976159.536447] 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.64admin1 # [976159.536621] admin1 systemd-networkd[172]: lo: Link UP65admin1 # [976159.536626] admin1 systemd-networkd[172]: lo: Gained carrier66admin1 # [976159.536801] admin1 systemd-networkd[172]: eth1: Configuring with /etc/systemd/network/40-eth1.network.67admin1 # [976159.537208] admin1 systemd[1]: Started Network Management.68admin1 # [976159.537339] admin1 systemd-networkd[172]: eth1: Link UP69admin1 # [976159.538063] admin1 systemd-networkd[172]: eth1: Gained carrier70admin1 # [976159.539109] admin1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...71admin1 # [976159.596243] admin1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.72peer1 # [976158.734764] peer1 systemd-journald[78]: Journal started73peer1 # [976158.734816] peer1 systemd-journald[78]: Runtime Journal (/run/log/journal/07a16c8a90f24589b2bceabf2e5baff4) is 8M, max 2.5G, 2.4G free.74peer1 # [976158.742644] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.75peer1 # [976158.770213] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...76peer1 # [976158.771079] peer1 systemd[1]: Starting Network Name Resolution...77peer1 # [976158.771828] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...78peer1 # [976158.784013] peer1 systemd-journald[78]: Time spent on flushing to /var/log/journal/07a16c8a90f24589b2bceabf2e5baff4 is 1.692ms for 6 entries.79peer1 # [976158.784013] peer1 systemd-journald[78]: System Journal (/var/log/journal/07a16c8a90f24589b2bceabf2e5baff4) is 8M, max 4G, 3.9G free.80peer1 # [976158.827603] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.81peer1 # [976158.828510] peer1 systemd[1]: Reached target Preparation for Local File Systems.82peer1 # [976158.828646] peer1 systemd[1]: Reached target Local File Systems.83peer1 # [976158.829544] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.84peer1 # [976158.829595] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container85peer1 # [976158.830638] peer1 systemd[1]: Starting Save Transient machine-id to Disk...86peer1 # [976158.830679] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys87peer1 # [976158.899023] peer1 systemd[1]: Finished Firewall.88peer1 # [976158.899197] peer1 systemd[1]: Reached target Preparation for Network.89peer1 # [976158.899457] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.90peer1 # [976158.900626] peer1 systemd[1]: Starting Network Management...91peer1 # [976158.940851] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.92peer1 # [976158.942120] peer1 systemd[1]: Starting Create System Files and Directories...93peer1 # [976158.962104] peer1 systemd-tmpfiles[185]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted94peer1 # [976158.962335] peer1 systemd-tmpfiles[185]: fchmod() of /var/log/journal failed: Operation not permitted95peer1 # [976158.962505] peer1 systemd-tmpfiles[185]: fchmod() of /var/log/journal/07a16c8a90f24589b2bceabf2e5baff4 failed: Operation not permitted96peer1 # [976158.962740] peer1 systemd-tmpfiles[185]: fchmod() of /run/log/journal failed: Operation not permitted97peer1 # [976158.964357] peer1 systemd[1]: Finished Create System Files and Directories.98peer1 # [976158.965753] peer1 systemd[1]: Starting Rebuild Journal Catalog...99peer1 # [976158.966672] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...100peer1 # [976158.980027] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.101peer1 # [976158.989946] peer1 systemd[1]: Finished Rebuild Journal Catalog.102peer1 # [976158.991170] peer1 systemd[1]: Starting Update is Completed...103peer1 # [976159.005291] peer1 systemd[1]: Finished Update is Completed.104peer1 # [976159.528919] peer1 systemd-networkd[181]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted105peer1 # [976159.529038] peer1 systemd-networkd[181]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted106peer1 # [976159.536037] 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.107peer1 # [976159.536212] 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.108peer1 # [976159.536410] peer1 systemd-networkd[181]: lo: Link UP109peer1 # [976159.536415] peer1 systemd-networkd[181]: lo: Gained carrier110peer1 # [976159.536710] peer1 systemd-networkd[181]: eth1: Configuring with /etc/systemd/network/40-eth1.network.111peer1 # [976159.537202] peer1 systemd[1]: Started Network Management.112peer1 # [976159.537263] peer1 systemd-networkd[181]: eth1: Link UP113peer1 # [976159.538051] peer1 systemd-networkd[181]: eth1: Gained carrier114peer1 # [976159.538679] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...115peer1 # [976159.594852] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.116peer1 # [976159.843362] peer1 systemd-resolved[113]: Positive Trust Anchors:117peer1 # [976159.843373] peer1 systemd-resolved[113]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d118peer1 # [976159.843377] peer1 systemd-resolved[113]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16119peer1 # [976159.843412] peer1 systemd-resolved[113]: 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 test120peer1 # [976159.866731] peer1 systemd-resolved[113]: Using system hostname 'peer1'.121peer1 # [976159.868692] peer1 systemd[1]: Started Network Name Resolution.122peer1 # [976159.868778] peer1 systemd[1]: Reached target Network.123peer1 # [976159.868850] peer1 systemd[1]: Reached target System Initialization.124peer1 # [976159.869065] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container125peer1 # [976159.869093] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.126peer1 # [976159.869108] peer1 systemd[1]: Reached target Timer Units.127peer1 # [976159.869290] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.128peer1 # [976159.869515] peer1 systemd[1]: Listening on Nix Daemon Socket.129peer1 # [976159.869652] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.130peer1 # [976159.869677] peer1 systemd[1]: Reached target Socket Units.131peer1 # [976159.869894] peer1 systemd[1]: Reached target Basic System.132peer1 # [976159.871476] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...133peer1 # [976159.872461] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...134peer1 # [976159.873841] peer1 systemd[1]: Starting D-Bus System Message Bus...135peer1 # [976159.933296] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.136admin1 # [976159.896366] admin1 systemd-resolved[104]: Positive Trust Anchors:137admin1 # [976159.896377] admin1 systemd-resolved[104]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d138admin1 # [976159.896382] admin1 systemd-resolved[104]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16139admin1 # [976159.896418] admin1 systemd-resolved[104]: 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 test140admin1 # [976159.923913] admin1 systemd-resolved[104]: Using system hostname 'admin1'.141admin1 # [976159.925424] admin1 systemd[1]: Started Network Name Resolution.142admin1 # [976159.925569] admin1 systemd[1]: Reached target Network.143admin1 # [976159.925691] admin1 systemd[1]: Reached target System Initialization.144admin1 # [976159.925790] admin1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container145admin1 # [976159.925851] admin1 systemd[1]: Started Daily Cleanup of Temporary Directories.146admin1 # [976159.925896] admin1 systemd[1]: Reached target Timer Units.147admin1 # [976159.926140] admin1 systemd[1]: Listening on D-Bus System Message Bus Socket.148admin1 # [976159.926395] admin1 systemd[1]: Listening on Nix Daemon Socket.149admin1 # [976159.926648] admin1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.150admin1 # [976159.926694] admin1 systemd[1]: Reached target Socket Units.151admin1 # [976159.926772] admin1 systemd[1]: Reached target Basic System.152admin1 # [976159.928994] admin1 systemd[1]: Starting Import lastlog data into lastlog2 database...153admin1 # [976159.930531] admin1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...154admin1 # [976159.932800] admin1 systemd[1]: Starting D-Bus System Message Bus...155admin1 # [976159.948952] admin1 systemd[1]: Finished Import lastlog data into lastlog2 database.156admin1 # [976160.434042] admin1 nsncd[188]: Sep 15 14:36:20.419 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"157admin1 # [976160.434126] admin1 systemd[1]: Started Name Service Cache Daemon (nsncd).158admin1 # [976160.434199] admin1 systemd[1]: Reached target Host and Network Name Lookups.159admin1 # [976160.434265] admin1 systemd[1]: Reached target User and Group Name Lookups.160admin1 # [976160.435866] admin1 systemd[1]: Starting User Login Management...161admin1 # [976160.436862] admin1 systemd[1]: Starting Permit User Sessions...162admin1 # [976160.503877] admin1 systemd[1]: Finished Permit User Sessions.163admin1 # [976160.504936] admin1 systemd[1]: Started Console Getty.164admin1 # [976160.504975] admin1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0165admin1 # [976160.504997] admin1 systemd[1]: Reached target Login Prompts.166admin1 # [976160.579932] admin1 dbus-broker-launch[189]: Looking up NSS user entry for 'systemd-timesync'...167admin1 # [976160.580695] admin1 dbus-broker-launch[189]: NSS returned no entry for 'systemd-timesync'168admin1 # [976160.580695] 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"169admin1 # [976160.581228] admin1 systemd[1]: Started D-Bus System Message Bus.170admin1 # [976160.588541] admin1 dbus-broker-launch[189]: Ready171peer1 # [976160.429434] peer1 nsncd[197]: Sep 15 14:36:20.415 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"172peer1 # [976160.429503] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).173peer1 # [976160.429581] peer1 systemd[1]: Reached target Host and Network Name Lookups.174peer1 # [976160.429649] peer1 systemd[1]: Reached target User and Group Name Lookups.175peer1 # [976160.431276] peer1 systemd[1]: Starting User Login Management...176peer1 # [976160.432514] peer1 systemd[1]: Starting Permit User Sessions...177peer1 # [976160.503546] peer1 systemd[1]: Finished Permit User Sessions.178peer1 # [976160.504707] peer1 systemd[1]: Started Console Getty.179peer1 # [976160.504756] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0180peer1 # [976160.504777] peer1 systemd[1]: Reached target Login Prompts.181peer1 # [976160.597657] peer1 dbus-broker-launch[198]: Looking up NSS user entry for 'systemd-timesync'...182peer1 # [976160.598397] peer1 dbus-broker-launch[198]: NSS returned no entry for 'systemd-timesync'183peer1 # [976160.598397] 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"184peer1 # [976160.598825] peer1 systemd[1]: Started D-Bus System Message Bus.185peer1 # [976160.606591] peer1 dbus-broker-launch[198]: Ready186admin1 # [976160.772214] admin1 systemd-networkd[172]: eth1: Gained IPv6LL187admin1 # [976161.124963] admin1 systemd-logind[204]: New seat seat0.188admin1 # [976161.125176] admin1 systemd[1]: Started User Login Management.189admin1 # [976161.128259] admin1 systemd[1]: Starting linger-users.service...190peer1 # [976161.124964] peer1 systemd-logind[213]: New seat seat0.191admin1 # [976161.179277] admin1 systemd[1]: linger-users.service: Deactivated successfully.192peer1 # [976161.125123] peer1 systemd[1]: Started User Login Management.193admin1 # [976161.179460] admin1 systemd[1]: Finished linger-users.service.194peer1 # [976161.126844] peer1 systemd[1]: Starting linger-users.service...195admin1 # [976161.179921] admin1 systemd[1]: Reached target Multi-User System.196peer1 # [976161.181126] peer1 systemd[1]: linger-users.service: Deactivated successfully.197peer1 # [976161.181212] peer1 systemd[1]: Finished linger-users.service.198peer1 # [976161.181646] peer1 systemd[1]: Reached target Multi-User System.199peer1 # [976161.472141] peer1 systemd-networkd[181]: eth1: Gained IPv6LL200admin1: still waiting for container 'admin1' to reach ready state...201admin1: still waiting for container 'admin1' to reach ready state...202admin1: still waiting for container 'admin1' to reach ready state...203admin1: still waiting for container 'admin1' to reach ready state...204admin1: still waiting for container 'admin1' to reach ready state...205admin1: 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...209peer1 # [976248.913653] peer1 systemd[1]: systemd-machine-id-commit.service: start operation timed out. Terminating.210admin1 # [976248.939542] admin1 systemd[1]: systemd-machine-id-commit.service: start operation timed out. Terminating.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: still waiting for container 'admin1' to reach ready state...215admin1: still waiting for container 'admin1' to reach ready state...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...220peer1 # [976339.163652] peer1 systemd[1]: systemd-machine-id-commit.service: State 'stop-sigterm' timed out. Killing.221peer1 # [976339.163749] peer1 systemd[1]: systemd-machine-id-commit.service: Killing process 143 (systemd-machine) with signal SIGKILL.222admin1 # [976339.189565] admin1 systemd[1]: systemd-machine-id-commit.service: State 'stop-sigterm' timed out. Killing.223admin1 # [976339.189665] admin1 systemd[1]: systemd-machine-id-commit.service: Killing process 130 (systemd-machine) with signal SIGKILL.224admin1: still waiting for container 'admin1' to reach ready state...225admin1: still waiting for container 'admin1' to reach ready state...226admin1: still waiting for container 'admin1' to reach ready state...227admin1: still waiting for container 'admin1' to reach ready state...228admin1: still waiting for container 'admin1' to reach ready state...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 # [976429.189678] admin1 systemd[1]: systemd-machine-id-commit.service: Processes still around after SIGKILL. Ignoring.234peer1 # [976429.413559] peer1 systemd[1]: systemd-machine-id-commit.service: Processes still around after SIGKILL. Ignoring.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: still waiting for container 'admin1' to reach ready state...239admin1: still waiting for container 'admin1' to reach ready state...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 # [976519.439546] admin1 systemd[1]: systemd-machine-id-commit.service: State 'final-sigterm' timed out. Killing.245admin1 # [976519.439609] admin1 systemd[1]: systemd-machine-id-commit.service: Killing process 130 (systemd-machine) with signal SIGKILL.246peer1 # [976519.663580] peer1 systemd[1]: systemd-machine-id-commit.service: State 'final-sigterm' timed out. Killing.247peer1 # [976519.663666] peer1 systemd[1]: systemd-machine-id-commit.service: Killing process 143 (systemd-machine) with signal SIGKILL.248admin1: still waiting for container 'admin1' to reach ready state...249admin1: still waiting for container 'admin1' to reach ready state...250admin1: still waiting for container 'admin1' to reach ready state...251admin1: still waiting for container 'admin1' to reach ready state...252admin1: still waiting for container 'admin1' to reach ready state...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: (finished: waiting for unit multi-user.target, in 452.34 seconds)258peer1: waiting for unit multi-user.target259peer1: (finished: waiting for unit multi-user.target, in 0.03 seconds)260peer1: must succeed: cat /nix/store/z61c0lalzvfahapklpif5sc0cd4x5g4b-per-machine-peer1-new-service_not-a-secret261peer1: (finished: must succeed: cat /nix/store/z61c0lalzvfahapklpif5sc0cd4x5g4b-per-machine-peer1-new-service_not-a-secret, in 0.01 seconds)262peer1: must succeed: ls -la /run/secrets/per-machine/peer1/new-service/a-secret263peer1: (finished: must succeed: ls -la /run/secrets/per-machine/peer1/new-service/a-secret, in 0.01 seconds)264admin1 # [976609.689567] admin1 systemd[1]: systemd-machine-id-commit.service: Processes still around after final SIGKILL. Entering failed mode.265admin1 # [976609.689605] admin1 systemd[1]: systemd-machine-id-commit.service: Failed with result 'timeout'.266admin1 # [976609.690213] admin1 systemd[1]: systemd-machine-id-commit.service: Unit process 130 (systemd-machine) remains running after unit stopped.267admin1 # [976609.691725] admin1 systemd[1]: Failed to start Save Transient machine-id to Disk.268admin1 # [976609.697022] admin1 systemd[1]: Startup finished in 7min 31.418s.269admin1 # [976609.697258] admin1 systemd[1]: systemd-machine-id-commit.service: Consumed 1.488s CPU time over 7min 30.869s wall clock time.270peer1 # [976609.913573] peer1 systemd[1]: systemd-machine-id-commit.service: Processes still around after final SIGKILL. Entering failed mode.271peer1 # [976609.913598] peer1 systemd[1]: systemd-machine-id-commit.service: Failed with result 'timeout'.272peer1 # [976609.913775] peer1 systemd[1]: systemd-machine-id-commit.service: Unit process 143 (systemd-machine) remains running after unit stopped.273peer1 # [976609.914248] peer1 systemd[1]: Failed to start Save Transient machine-id to Disk.274peer1 # [976609.915116] peer1 systemd[1]: Startup finished in 7min 31.596s.275peer1 # [976609.915214] peer1 systemd[1]: systemd-machine-id-commit.service: Consumed 1.488s CPU time over 7min 31.083s wall clock time.276admin1277peer1278(finished: run the VM test script, in 481.27 seconds)279test script finished in 811.97s280cleanup281kill NspawnMachine (pid 52)282kill NspawnMachine (pid 53)283Container admin1 terminated by signal KILL.284Container peer1 terminated by signal KILL.285(finished: cleanup, in 0.49 seconds)