container-test-run-service-dummy-test-from-flake
checks.aarch64-linux.service-dummy-test-from-flake
· build #632
· 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.23░ Spawning container peer1 on /build/vm-state-peer1.24Note: 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.25░ Spawning container admin1 on /build/vm-state-admin1.26admin1 # [609290.189942] admin1 systemd-journald[69]: Journal started27admin1 # [609290.189991] admin1 systemd-journald[69]: Runtime Journal (/run/log/journal/98d751bd8a8b4d3b9f62aed947020f47) is 8M, max 2.5G, 2.4G free.28admin1 # [609290.196281] admin1 systemd[1]: Finished Apply Kernel Variables.29admin1 # [609290.204758] admin1 systemd[1]: Starting Flush Journal to Persistent Storage...30admin1 # [609290.205840] admin1 systemd[1]: Starting Network Name Resolution...31admin1 # [609290.206641] admin1 systemd[1]: Starting Create Static Device Nodes in /dev...32admin1 # [609290.216878] admin1 systemd-journald[69]: Time spent on flushing to /var/log/journal/98d751bd8a8b4d3b9f62aed947020f47 is 1.485ms for 6 entries.33admin1 # [609290.216878] admin1 systemd-journald[69]: System Journal (/var/log/journal/98d751bd8a8b4d3b9f62aed947020f47) is 8M, max 4G, 3.9G free.34admin1 # [609290.232548] admin1 systemd[1]: Finished Flush Journal to Persistent Storage.35admin1 # [609290.236138] admin1 systemd[1]: Finished Create Static Device Nodes in /dev.36admin1 # [609290.237303] admin1 systemd[1]: Reached target Preparation for Local File Systems.37admin1 # [609290.237451] admin1 systemd[1]: Reached target Local File Systems.38admin1 # [609290.238445] admin1 systemd[1]: Listening on Boot Loader Control Service Socket.39admin1 # [609290.238498] admin1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container40admin1 # [609290.239415] admin1 systemd[1]: Starting Save Transient machine-id to Disk...41admin1 # [609290.240264] admin1 systemd[1]: Starting Create System Files and Directories...42admin1 # [609290.240298] admin1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys43admin1 # [609290.283466] admin1 systemd-tmpfiles[108]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted44admin1 # [609290.283657] admin1 systemd-tmpfiles[108]: fchmod() of /var/log/journal failed: Operation not permitted45admin1 # [609290.283791] admin1 systemd-tmpfiles[108]: fchmod() of /var/log/journal/98d751bd8a8b4d3b9f62aed947020f47 failed: Operation not permitted46admin1 # [609290.283991] admin1 systemd-tmpfiles[108]: fchmod() of /run/log/journal failed: Operation not permitted47admin1 # [609290.285720] admin1 systemd[1]: Finished Create System Files and Directories.48admin1 # [609290.286958] admin1 systemd[1]: Starting Rebuild Journal Catalog...49admin1 # [609290.287787] admin1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...50peer1 # [609290.205980] peer1 systemd-journald[78]: Journal started51peer1 # [609290.206075] peer1 systemd-journald[78]: Runtime Journal (/run/log/journal/c8888f719891460fac64e63a6e5121d6) is 8M, max 2.5G, 2.4G free.52peer1 # [609290.210678] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...53peer1 # [609290.221223] peer1 systemd-journald[78]: Time spent on flushing to /var/log/journal/c8888f719891460fac64e63a6e5121d6 is 1.380ms for 3 entries.54peer1 # [609290.221223] peer1 systemd-journald[78]: System Journal (/var/log/journal/c8888f719891460fac64e63a6e5121d6) is 8M, max 4G, 3.9G free.55peer1 # [609290.236045] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.56peer1 # [609290.236239] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.57peer1 # [609290.237435] peer1 systemd[1]: Reached target Preparation for Local File Systems.58peer1 # [609290.237548] peer1 systemd[1]: Reached target Local File Systems.59peer1 # [609290.238430] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.60peer1 # [609290.238486] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container61peer1 # [609290.239513] peer1 systemd[1]: Starting Save Transient machine-id to Disk...62peer1 # [609290.240428] peer1 systemd[1]: Starting Create System Files and Directories...63peer1 # [609290.240459] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys64peer1 # [609290.283414] peer1 systemd-tmpfiles[126]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted65peer1 # [609290.283612] peer1 systemd-tmpfiles[126]: fchmod() of /var/log/journal failed: Operation not permitted66peer1 # [609290.283751] peer1 systemd-tmpfiles[126]: fchmod() of /var/log/journal/c8888f719891460fac64e63a6e5121d6 failed: Operation not permitted67peer1 # [609290.283961] peer1 systemd-tmpfiles[126]: fchmod() of /run/log/journal failed: Operation not permitted68peer1 # [609290.285784] peer1 systemd[1]: Finished Create System Files and Directories.69peer1 # [609290.286931] peer1 systemd[1]: Starting Rebuild Journal Catalog...70peer1 # [609290.287802] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...71peer1 # [609290.302163] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.72peer1 # [609290.306700] peer1 systemd[1]: Finished Rebuild Journal Catalog.73peer1 # [609290.308181] peer1 systemd[1]: Starting Update is Completed...74peer1 # [609290.321444] peer1 systemd[1]: Finished Update is Completed.75peer1 # [609290.357550] peer1 systemd[1]: Finished Firewall.76admin1 # [609290.301171] admin1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.77admin1 # [609290.310647] admin1 systemd[1]: Finished Rebuild Journal Catalog.78admin1 # [609290.311766] admin1 systemd[1]: Starting Update is Completed...79peer1 # [609290.357729] peer1 systemd[1]: Reached target Preparation for Network.80admin1 # [609290.323921] admin1 systemd[1]: Finished Update is Completed.81peer1 # [609290.357986] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.82admin1 # [609290.376557] admin1 systemd[1]: Finished Firewall.83peer1 # [609290.359218] peer1 systemd[1]: Starting Network Management...84admin1 # [609290.376744] admin1 systemd[1]: Reached target Preparation for Network.85admin1 # [609290.377000] admin1 systemd[1]: Listening on Network Management Resolve Hook Socket.86admin1 # [609290.378221] admin1 systemd[1]: Starting Network Management...87admin1 # [609291.368885] admin1 systemd-networkd[182]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted88admin1 # [609291.368979] admin1 systemd-networkd[182]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted89admin1 # [609291.375896] admin1 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.90admin1 # [609291.376079] admin1 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.91admin1 # [609291.376243] admin1 systemd-networkd[182]: lo: Link UP92admin1 # [609291.376248] admin1 systemd-networkd[182]: lo: Gained carrier93admin1 # [609291.376445] admin1 systemd-networkd[182]: eth1: Configuring with /etc/systemd/network/40-eth1.network.94admin1 # [609291.376821] admin1 systemd[1]: Started Network Management.95admin1 # [609291.376933] admin1 systemd-networkd[182]: eth1: Link UP96admin1 # [609291.377169] admin1 systemd-networkd[182]: eth1: Gained carrier97admin1 # [609291.378362] admin1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...98admin1 # [609291.431819] admin1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.99admin1 # [609291.591834] admin1 systemd-resolved[85]: Positive Trust Anchors:100admin1 # [609291.591847] admin1 systemd-resolved[85]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d101admin1 # [609291.591850] admin1 systemd-resolved[85]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16102admin1 # [609291.591884] admin1 systemd-resolved[85]: 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 test103admin1 # [609291.614468] admin1 systemd-resolved[85]: Using system hostname 'admin1'.104admin1 # [609291.615909] admin1 systemd[1]: Started Network Name Resolution.105admin1 # [609291.616029] admin1 systemd[1]: Reached target Network.106admin1 # [609291.616106] admin1 systemd[1]: Reached target System Initialization.107admin1 # [609291.616157] admin1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container108admin1 # [609291.616185] admin1 systemd[1]: Started Daily Cleanup of Temporary Directories.109admin1 # [609291.616202] admin1 systemd[1]: Reached target Timer Units.110admin1 # [609291.616339] admin1 systemd[1]: Listening on D-Bus System Message Bus Socket.111admin1 # [609291.616474] admin1 systemd[1]: Listening on Nix Daemon Socket.112admin1 # [609291.616590] admin1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.113admin1 # [609291.616611] admin1 systemd[1]: Reached target Socket Units.114admin1 # [609291.616658] admin1 systemd[1]: Reached target Basic System.115admin1 # [609291.617743] admin1 systemd[1]: Starting Import lastlog data into lastlog2 database...116admin1 # [609291.618782] admin1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...117admin1 # [609291.620142] admin1 systemd[1]: Starting D-Bus System Message Bus...118peer1 # [609291.427957] peer1 systemd-networkd[191]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted119peer1 # [609291.428096] peer1 systemd-networkd[191]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted120peer1 # [609291.436939] peer1 systemd-networkd[191]: /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.121peer1 # [609291.437131] peer1 systemd-networkd[191]: /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.122peer1 # [609291.437297] peer1 systemd-networkd[191]: lo: Link UP123peer1 # [609291.437300] peer1 systemd-networkd[191]: lo: Gained carrier124peer1 # [609291.437515] peer1 systemd-networkd[191]: eth1: Configuring with /etc/systemd/network/40-eth1.network.125peer1 # [609291.437939] peer1 systemd[1]: Started Network Management.126peer1 # [609291.438037] peer1 systemd-networkd[191]: eth1: Link UP127peer1 # [609291.438417] peer1 systemd-networkd[191]: eth1: Gained carrier128peer1 # [609291.439144] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...129peer1 # [609291.466217] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.130peer1 # [609291.621207] peer1 systemd-resolved[100]: Positive Trust Anchors:131peer1 # [609291.621219] peer1 systemd-resolved[100]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d132peer1 # [609291.621222] peer1 systemd-resolved[100]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16133peer1 # [609291.621258] peer1 systemd-resolved[100]: 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 test134peer1 # [609291.643981] peer1 systemd-resolved[100]: Using system hostname 'peer1'.135peer1 # [609291.645771] peer1 systemd[1]: Started Network Name Resolution.136peer1 # [609291.645857] peer1 systemd[1]: Reached target Network.137peer1 # [609291.645932] peer1 systemd[1]: Reached target System Initialization.138peer1 # [609291.645986] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container139peer1 # [609291.646015] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.140peer1 # [609291.646031] peer1 systemd[1]: Reached target Timer Units.141peer1 # [609291.646157] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.142peer1 # [609291.646286] peer1 systemd[1]: Listening on Nix Daemon Socket.143peer1 # [609291.646398] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.144peer1 # [609291.646418] peer1 systemd[1]: Reached target Socket Units.145peer1 # [609291.646459] peer1 systemd[1]: Reached target Basic System.146peer1 # [609291.660476] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...147peer1 # [609291.661504] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...148peer1 # [609291.662778] peer1 systemd[1]: Starting D-Bus System Message Bus...149peer1 # [609291.678927] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.150admin1 # [609291.676218] admin1 systemd[1]: Finished Import lastlog data into lastlog2 database.151peer1 # [609291.908538] peer1 nsncd[197]: Sep 11 08:41:51.894 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"152peer1 # [609291.908593] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).153peer1 # [609291.908668] peer1 systemd[1]: Reached target Host and Network Name Lookups.154peer1 # [609291.908739] peer1 systemd[1]: Reached target User and Group Name Lookups.155peer1 # [609291.928419] peer1 systemd[1]: Starting User Login Management...156peer1 # [609291.929397] peer1 systemd[1]: Starting Permit User Sessions...157peer1 # [609291.939103] peer1 systemd[1]: Finished Permit User Sessions.158admin1 # [609291.883451] admin1 nsncd[188]: Sep 11 08:41:51.869 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"159peer1 # [609291.940134] peer1 systemd[1]: Started Console Getty.160admin1 # [609291.883606] admin1 systemd[1]: Started Name Service Cache Daemon (nsncd).161peer1 # [609291.940182] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0162admin1 # [609291.883669] admin1 systemd[1]: Reached target Host and Network Name Lookups.163peer1 # [609291.940203] peer1 systemd[1]: Reached target Login Prompts.164admin1 # [609291.883732] admin1 systemd[1]: Reached target User and Group Name Lookups.165admin1 # [609291.885129] admin1 systemd[1]: Starting User Login Management...166peer1 # [609292.005454] peer1 dbus-broker-launch[198]: Looking up NSS user entry for 'systemd-timesync'...167admin1 # [609291.885952] admin1 systemd[1]: Starting Permit User Sessions...168peer1 # [609292.006234] peer1 dbus-broker-launch[198]: NSS returned no entry for 'systemd-timesync'169admin1 # [609291.935028] admin1 systemd[1]: Finished Permit User Sessions.170peer1 # [609292.006234] 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"171peer1 # [609292.006667] peer1 systemd[1]: Started D-Bus System Message Bus.172admin1 # [609291.936325] admin1 systemd[1]: Started Console Getty.173peer1 # [609292.013553] peer1 dbus-broker-launch[198]: Ready174admin1 # [609291.936387] admin1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0175admin1 # [609291.936410] admin1 systemd[1]: Reached target Login Prompts.176admin1 # [609291.986256] admin1 dbus-broker-launch[189]: Looking up NSS user entry for 'systemd-timesync'...177admin1 # [609291.987082] admin1 dbus-broker-launch[189]: NSS returned no entry for 'systemd-timesync'178admin1 # [609291.987082] 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"179admin1 # [609291.989771] admin1 systemd[1]: Started D-Bus System Message Bus.180admin1 # [609291.997025] admin1 dbus-broker-launch[189]: Ready181peer1 # [609292.412411] peer1 systemd-logind[213]: New seat seat0.182peer1 # [609292.412649] peer1 systemd[1]: Started User Login Management.183peer1 # [609292.415072] peer1 systemd[1]: Starting linger-users.service...184peer1 # [609292.462102] peer1 systemd[1]: linger-users.service: Deactivated successfully.185peer1 # [609292.462248] peer1 systemd[1]: Finished linger-users.service.186peer1 # [609292.462665] peer1 systemd[1]: Reached target Multi-User System.187admin1 # [609292.459351] admin1 systemd-logind[204]: New seat seat0.188admin1 # [609292.459542] admin1 systemd[1]: Started User Login Management.189admin1 # [609292.461765] admin1 systemd[1]: Starting linger-users.service...190admin1 # [609292.473906] admin1 systemd[1]: linger-users.service: Deactivated successfully.191admin1 # [609292.473995] admin1 systemd[1]: Finished linger-users.service.192admin1 # [609292.474479] admin1 systemd[1]: Reached target Multi-User System.193peer1 # [609292.960206] peer1 systemd-networkd[191]: eth1: Gained IPv6LL194admin1 # [609293.092214] admin1 systemd-networkd[182]: eth1: Gained IPv6LL195admin1 # [609296.113521] admin1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.196admin1 # [609296.115180] admin1 systemd[1]: Finished Save Transient machine-id to Disk.197admin1 # [609296.115573] admin1 systemd[1]: Startup finished in 6.356s.198peer1 # [609296.119605] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.199peer1 # [609296.122439] peer1 systemd[1]: Finished Save Transient machine-id to Disk.200peer1 # [609296.122902] peer1 systemd[1]: Startup finished in 6.339s.201admin1: (finished: waiting for unit multi-user.target, in 7.16 seconds)202peer1: waiting for unit multi-user.target203peer1: (finished: waiting for unit multi-user.target, in 0.01 seconds)204peer1: must succeed: cat /nix/store/z61c0lalzvfahapklpif5sc0cd4x5g4b-per-machine-peer1-new-service_not-a-secret205peer1: (finished: must succeed: cat /nix/store/z61c0lalzvfahapklpif5sc0cd4x5g4b-per-machine-peer1-new-service_not-a-secret, in 0.01 seconds)206peer1: must succeed: ls -la /run/secrets/per-machine/peer1/new-service/a-secret207peer1: (finished: must succeed: ls -la /run/secrets/per-machine/peer1/new-service/a-secret, in 0.01 seconds)208admin1209peer1210(finished: run the VM test script, in 13.24 seconds)211test script finished in 14.81s212cleanup213kill NspawnMachine (pid 52)214kill NspawnMachine (pid 53)215Container admin1 terminated by signal KILL.216Container peer1 terminated by signal KILL.217(finished: cleanup, in 0.43 seconds)