container-test-run-dm-wireguard-star
checks.aarch64-linux.dm-wireguard-star
· build #46
· 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 controller, 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_ssh11controller: systemd-nspawn running (pid 50)12controller: Waiting for journal at /build/vm-state-controller/var/log/journal...13peer1: systemd-nspawn running (pid 53)14peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...15controller: waiting for unit data-mesher.service16nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE17nixos-nspawn(controller): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.18nixos-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.20Note: 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.21Note: 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.22░ Spawning container controller on /build/vm-state-controller.23░ Spawning container peer1 on /build/vm-state-peer1.24controller # [6432037.853262] controller systemd-journald[105]: Journal started25controller # [6432037.853318] controller systemd-journald[105]: Runtime Journal (/run/log/journal/cdac70fdaf8c41a08d042102ba8ea600) is 8M, max 2.5G, 2.4G free.26controller # [6432037.856625] controller systemd[1]: Starting Flush Journal to Persistent Storage...27controller # [6432037.857434] controller systemd[1]: Starting Network Name Resolution...28controller # [6432037.858106] controller systemd[1]: Starting Create Static Device Nodes in /dev...29controller # [6432037.866843] controller systemd-journald[105]: Time spent on flushing to /var/log/journal/cdac70fdaf8c41a08d042102ba8ea600 is 1.553ms for 5 entries.30peer1 # No journal boot entry found for the specified boot (+0).31controller # [6432037.866843] controller systemd-journald[105]: System Journal (/var/log/journal/cdac70fdaf8c41a08d042102ba8ea600) is 8M, max 4G, 3.9G free.32controller # [6432037.874192] controller systemd[1]: Finished Create Static Device Nodes in /dev.33controller # [6432037.874421] controller systemd[1]: Reached target Preparation for Local File Systems.34controller # [6432037.874512] controller systemd[1]: Reached target Local File Systems.35controller # [6432037.875238] controller systemd[1]: Listening on Boot Loader Control Service Socket.36controller # [6432037.875283] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container37controller # [6432037.876189] controller systemd[1]: Starting Save Transient machine-id to Disk...38controller # [6432037.876220] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys39controller # [6432037.879732] controller systemd[1]: Finished Flush Journal to Persistent Storage.40controller # [6432037.880542] controller systemd[1]: Starting Create System Files and Directories...41controller # [6432037.899035] controller systemd-tmpfiles[144]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted42controller # [6432037.899253] controller systemd-tmpfiles[144]: fchmod() of /var/log/journal failed: Operation not permitted43controller # [6432037.899401] controller systemd-tmpfiles[144]: fchmod() of /var/log/journal/cdac70fdaf8c41a08d042102ba8ea600 failed: Operation not permitted44controller # [6432037.899620] controller systemd-tmpfiles[144]: fchmod() of /run/log/journal failed: Operation not permitted45controller # [6432037.901099] controller systemd[1]: Finished Create System Files and Directories.46controller # [6432037.902108] controller systemd[1]: Starting Rebuild Journal Catalog...47controller # [6432037.903034] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...48controller # [6432037.916700] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.49controller # [6432037.926161] controller systemd[1]: Finished Rebuild Journal Catalog.50controller # [6432037.927162] controller systemd[1]: Starting Update is Completed...51controller # [6432037.937999] controller systemd[1]: Finished Update is Completed.52controller # [6432037.952691] controller systemd[1]: Finished Save Transient machine-id to Disk.53controller # [6432038.015577] controller systemd[1]: Finished Firewall.54controller # [6432038.015729] controller systemd[1]: Reached target Preparation for Network.55controller # [6432038.015946] controller systemd[1]: Listening on Network Management Resolve Hook Socket.56controller # [6432038.016987] controller systemd[1]: Starting Network Management...57controller # [6432038.424810] controller systemd-networkd[225]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted58controller # [6432038.424896] controller systemd-networkd[225]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted59controller # [6432038.431929] controller systemd-networkd[225]: /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.60controller # [6432038.432104] controller systemd-networkd[225]: /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.61controller # [6432038.432247] controller systemd-networkd[225]: lo: Link UP62controller # [6432038.432251] controller systemd-networkd[225]: lo: Gained carrier63controller # [6432038.432418] controller systemd-networkd[225]: eth1: Configuring with /etc/systemd/network/40-eth1.network.64controller # [6432038.432816] controller systemd[1]: Started Network Management.65controller # [6432038.444500] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...66controller # [6432038.445178] controller systemd-networkd[225]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.67controller # [6432038.445501] controller systemd-networkd[225]: wg-star: netdev ready68controller # [6432038.445873] controller systemd-networkd[225]: eth1: Link UP69controller # [6432038.446083] controller systemd-networkd[225]: eth1: Gained carrier70controller # [6432038.472693] controller systemd-networkd[225]: wg-star: Link UP71controller # [6432038.472698] controller systemd-networkd[225]: wg-star: Gained carrier72controller # [6432038.474207] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.73controller # [6432038.474521] controller systemd-resolved[125]: Positive Trust Anchors:74controller # [6432038.474533] controller systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d75controller # [6432038.474537] controller systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b1676controller # [6432038.474572] controller systemd-resolved[125]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test77controller # [6432038.496864] controller systemd-resolved[125]: Using system hostname 'controller'.78controller # [6432038.498378] controller systemd[1]: Started Network Name Resolution.79controller # [6432038.498453] controller systemd[1]: Reached target Network.80controller # [6432038.498534] controller systemd[1]: Reached target System Initialization.81controller # [6432038.498640] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.82controller # [6432038.498674] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container83controller # [6432038.498703] controller systemd[1]: Started Daily Cleanup of Temporary Directories.84controller # [6432038.498728] controller systemd[1]: Reached target Path Units.85controller # [6432038.498765] controller systemd[1]: Reached target Timer Units.86controller # [6432038.498902] controller systemd[1]: Listening on D-Bus System Message Bus Socket.87controller # [6432038.499036] controller systemd[1]: Listening on Nix Daemon Socket.88controller # [6432038.499157] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.89controller # [6432038.499185] controller systemd[1]: Reached target Socket Units.90controller # [6432038.499229] controller systemd[1]: Reached target Basic System.91controller # [6432038.500655] controller systemd[1]: Starting data mesher daemon...92controller # [6432038.501609] controller systemd[1]: Starting Import lastlog data into lastlog2 database...93controller # [6432038.502595] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...94controller # [6432038.504167] controller systemd[1]: Starting D-Bus System Message Bus...95controller # [6432038.558044] controller systemd[1]: Finished Import lastlog data into lastlog2 database.96controller # [6432038.663214] controller systemd[1]: Started Name Service Cache Daemon (nsncd).97controller # [6432038.663274] controller systemd[1]: Reached target Host and Network Name Lookups.98controller # [6432038.663349] controller systemd[1]: Reached target User and Group Name Lookups.99controller # [6432038.663522] controller nsncd[232]: Aug 22 10:04:24.716 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"100controller # [6432038.664781] controller systemd[1]: Starting User Login Management...101controller # [6432038.665694] controller systemd[1]: Starting Permit User Sessions...102peer1 # [6432037.861413] peer1 systemd-journald[96]: Journal started103peer1 # [6432037.861467] peer1 systemd-journald[96]: Runtime Journal (/run/log/journal/8c51fc82788b4cbbb47ff2200c6c213e) is 8M, max 2.5G, 2.4G free.104peer1 # [6432037.864690] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...105peer1 # [6432037.865605] peer1 systemd[1]: Starting Network Name Resolution...106peer1 # [6432037.866298] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...107peer1 # [6432037.875968] peer1 systemd-journald[96]: Time spent on flushing to /var/log/journal/8c51fc82788b4cbbb47ff2200c6c213e is 1.408ms for 5 entries.108peer1 # [6432037.875968] peer1 systemd-journald[96]: System Journal (/var/log/journal/8c51fc82788b4cbbb47ff2200c6c213e) is 8M, max 4G, 3.9G free.109peer1 # [6432037.882480] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.110peer1 # [6432037.882704] peer1 systemd[1]: Reached target Preparation for Local File Systems.111peer1 # [6432037.882789] peer1 systemd[1]: Reached target Local File Systems.112peer1 # [6432037.883508] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.113peer1 # [6432037.883557] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container114peer1 # [6432037.884335] peer1 systemd[1]: Starting Save Transient machine-id to Disk...115peer1 # [6432037.884373] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys116peer1 # [6432037.884800] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.117peer1 # [6432037.886009] peer1 systemd[1]: Starting Create System Files and Directories...118peer1 # [6432037.903707] peer1 systemd-tmpfiles[133]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted119peer1 # [6432037.903917] peer1 systemd-tmpfiles[133]: fchmod() of /var/log/journal failed: Operation not permitted120peer1 # [6432037.904098] peer1 systemd-tmpfiles[133]: fchmod() of /var/log/journal/8c51fc82788b4cbbb47ff2200c6c213e failed: Operation not permitted121peer1 # [6432037.904338] peer1 systemd-tmpfiles[133]: fchmod() of /run/log/journal failed: Operation not permitted122peer1 # [6432037.905752] peer1 systemd[1]: Finished Create System Files and Directories.123peer1 # [6432037.906886] peer1 systemd[1]: Starting Rebuild Journal Catalog...124peer1 # [6432037.907537] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...125peer1 # [6432037.919684] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.126peer1 # [6432037.929616] peer1 systemd[1]: Finished Rebuild Journal Catalog.127peer1 # [6432037.931106] peer1 systemd[1]: Starting Update is Completed...128peer1 # [6432037.942649] peer1 systemd[1]: Finished Update is Completed.129peer1 # [6432037.953054] peer1 systemd[1]: Finished Save Transient machine-id to Disk.130peer1 # [6432038.056207] peer1 systemd[1]: Finished Firewall.131peer1 # [6432038.056384] peer1 systemd[1]: Reached target Preparation for Network.132peer1 # [6432038.056587] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.133peer1 # [6432038.057649] peer1 systemd[1]: Starting Network Management...134peer1 # [6432038.404852] peer1 systemd-networkd[214]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted135peer1 # [6432038.404947] peer1 systemd-networkd[214]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted136peer1 # [6432038.413052] peer1 systemd-networkd[214]: /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.137peer1 # [6432038.413213] peer1 systemd-networkd[214]: /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.138peer1 # [6432038.413368] peer1 systemd-networkd[214]: lo: Link UP139peer1 # [6432038.413373] peer1 systemd-networkd[214]: lo: Gained carrier140peer1 # [6432038.413571] peer1 systemd-networkd[214]: eth1: Configuring with /etc/systemd/network/40-eth1.network.141peer1 # [6432038.413986] peer1 systemd[1]: Started Network Management.142peer1 # [6432038.414888] peer1 systemd-networkd[214]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.143peer1 # [6432038.415065] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...144peer1 # [6432038.415220] peer1 systemd-networkd[214]: wg-star: netdev ready145peer1 # [6432038.415811] peer1 systemd-networkd[214]: eth1: Link UP146peer1 # [6432038.416036] peer1 systemd-networkd[214]: eth1: Gained carrier147peer1 # [6432038.461308] peer1 systemd-networkd[214]: wg-star: Link UP148peer1 # [6432038.461313] peer1 systemd-networkd[214]: wg-star: Gained carrier149peer1 # [6432038.462811] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.150peer1 # [6432038.475874] peer1 systemd-resolved[116]: Positive Trust Anchors:151peer1 # [6432038.475886] peer1 systemd-resolved[116]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d152peer1 # [6432038.475889] peer1 systemd-resolved[116]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16153peer1 # [6432038.475925] peer1 systemd-resolved[116]: 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 test154peer1 # [6432038.497914] peer1 systemd-resolved[116]: Using system hostname 'peer1'.155peer1 # [6432038.499448] peer1 systemd[1]: Started Network Name Resolution.156peer1 # [6432038.499571] peer1 systemd[1]: Reached target Network.157peer1 # [6432038.499691] peer1 systemd[1]: Reached target System Initialization.158peer1 # [6432038.499848] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.159peer1 # [6432038.499907] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.160peer1 # [6432038.499954] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container161peer1 # [6432038.499996] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.162peer1 # [6432038.500053] peer1 systemd[1]: Reached target Path Units.163peer1 # [6432038.500125] peer1 systemd[1]: Reached target Timer Units.164peer1 # [6432038.500337] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.165peer1 # [6432038.500542] peer1 systemd[1]: Listening on Nix Daemon Socket.166peer1 # [6432038.500758] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.167peer1 # [6432038.500809] peer1 systemd[1]: Reached target Socket Units.168peer1 # [6432038.500897] peer1 systemd[1]: Reached target Basic System.169peer1 # [6432038.503015] peer1 systemd[1]: Starting data mesher daemon...170peer1 # [6432038.504505] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...171peer1 # [6432038.505876] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...172peer1 # [6432038.540935] peer1 systemd[1]: Starting D-Bus System Message Bus...173peer1 # [6432038.559112] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.174peer1 # [6432038.659639] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).175peer1 # [6432038.659705] peer1 systemd[1]: Reached target Host and Network Name Lookups.176peer1 # [6432038.659778] peer1 systemd[1]: Reached target User and Group Name Lookups.177peer1 # [6432038.660852] peer1 nsncd[221]: Aug 22 10:04:24.713 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"178peer1 # [6432038.661378] peer1 systemd[1]: Starting User Login Management...179peer1 # [6432038.662365] peer1 systemd[1]: Starting Permit User Sessions...180peer1 # [6432038.707537] peer1 systemd[1]: Finished Permit User Sessions.181peer1 # [6432038.710027] peer1 systemd[1]: Started Console Getty.182peer1 # [6432038.710106] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0183peer1 # [6432038.710148] peer1 systemd[1]: Reached target Login Prompts.184peer1 # [6432038.731960] peer1 dbus-broker-launch[222]: Looking up NSS user entry for 'systemd-timesync'...185peer1 # [6432038.733218] peer1 dbus-broker-launch[222]: NSS returned no entry for 'systemd-timesync'186peer1 # [6432038.733218] peer1 dbus-broker-launch[222]: Invalid user-name in /nix/store/z2330ck6iq53jg1pf081mhmw858hsszq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"187peer1 # [6432038.733665] peer1 systemd[1]: Started D-Bus System Message Bus.188peer1 # [6432038.742597] peer1 dbus-broker-launch[222]: Ready189peer1 # [6432038.853596] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.190controller # [6432038.708867] controller systemd[1]: Finished Permit User Sessions.191controller # [6432038.710419] controller systemd[1]: Started Console Getty.192controller # [6432038.710489] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0193controller # [6432038.710528] controller systemd[1]: Reached target Login Prompts.194controller # [6432038.740520] controller dbus-broker-launch[233]: Looking up NSS user entry for 'systemd-timesync'...195controller # [6432038.742064] controller dbus-broker-launch[233]: NSS returned no entry for 'systemd-timesync'196controller # [6432038.742064] controller dbus-broker-launch[233]: Invalid user-name in /nix/store/z2330ck6iq53jg1pf081mhmw858hsszq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"197controller # [6432038.742497] controller systemd[1]: Started D-Bus System Message Bus.198controller # [6432038.751383] controller dbus-broker-launch[233]: Ready199controller # [6432038.844908] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.200peer1 # [6432038.975664] peer1 data-mesher[219]: time=2026-08-22T10:04:25.028Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]201peer1 # [6432038.977010] peer1 data-mesher[219]: time=2026-08-22T10:04:25.030Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH: [/dns/controller.clan/tcp/7946]} {12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj: [/dns/peer1.clan/tcp/7946]} {12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj202peer1 # [6432038.977010] peer1 data-mesher[219]: time=2026-08-22T10:04:25.030Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml203peer1 # [6432038.980248] peer1 data-mesher[219]: time=2026-08-22T10:04:25.033Z level=INFO msg="checking file integrity"204peer1 # [6432038.980350] peer1 data-mesher[219]: time=2026-08-22T10:04:25.033Z level=INFO msg="file integrity check complete"205peer1 # [6432038.984261] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="libp2p host created" peer_id=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946 /ip6/fda1:5c8::bf54:ef5a:5618:ddb5/tcp/7946]"206peer1 # [6432038.984306] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name207peer1 # [6432038.984306] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="registered HTTP route" method=GET path=/files208peer1 # [6432038.984306] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name209peer1 # [6432038.984306] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="starting server"210peer1 # [6432038.984413] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="waiting for DHT to populate" delay=10s211peer1 # [6432038.984501] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="HTTP server listening" address=[::1]:7331212peer1 # [6432038.984541] peer1 data-mesher[219]: time=2026-08-22T10:04:25.037Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331213peer1 # [6432038.999021] peer1 data-mesher[219]: time=2026-08-22T10:04:25.052Z level=INFO msg="peer connected" peer_id=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH remote_addr=/ip4/192.168.1.1/tcp/7946214peer1 # [6432039.164183] peer1 systemd-logind[239]: New seat seat0.215peer1 # [6432039.164368] peer1 systemd[1]: Started User Login Management.216peer1 # [6432039.180661] peer1 systemd[1]: Starting linger-users.service...217peer1 # [6432039.194541] peer1 systemd[1]: linger-users.service: Deactivated successfully.218peer1 # [6432039.194666] peer1 systemd[1]: Finished linger-users.service.219controller # [6432038.983248] controller data-mesher[230]: time=2026-08-22T10:04:25.036Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]220controller # [6432038.984297] controller data-mesher[230]: time=2026-08-22T10:04:25.037Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH: [/dns/controller.clan/tcp/7946]} {12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj: [/dns/peer1.clan/tcp/7946]} {12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH221controller # [6432038.984297] controller data-mesher[230]: time=2026-08-22T10:04:25.037Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml222controller # [6432038.985363] controller data-mesher[230]: time=2026-08-22T10:04:25.038Z level=INFO msg="checking file integrity"223controller # [6432038.985471] controller data-mesher[230]: time=2026-08-22T10:04:25.038Z level=INFO msg="file integrity check complete"224controller # [6432038.989057] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="libp2p host created" peer_id=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.1/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::1/tcp/7946 /ip6/fda1:5c8::a47f:6a0a:e23c:6aec/tcp/7946]"225controller # [6432038.989108] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="registered HTTP route" method=GET path=/files226controller # [6432038.989108] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name227controller # [6432038.989108] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name228controller # [6432038.989108] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="starting server"229controller # [6432038.989242] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="waiting for DHT to populate" delay=10s230controller # [6432038.989242] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="HTTP server listening" address=[::1]:7331231controller # [6432038.989322] controller data-mesher[230]: time=2026-08-22T10:04:25.042Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331232controller # [6432038.998242] controller data-mesher[230]: time=2026-08-22T10:04:25.051Z level=INFO msg="peer connected" peer_id=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj remote_addr=/ip4/192.168.1.2/tcp/7946233controller # [6432039.146565] controller systemd-logind[250]: New seat seat0.234controller # [6432039.146759] controller systemd[1]: Started User Login Management.235controller # [6432039.148942] controller systemd[1]: Starting linger-users.service...236controller # [6432039.190810] controller systemd[1]: linger-users.service: Deactivated successfully.237controller # [6432039.191059] controller systemd[1]: Finished linger-users.service.238controller # [6432039.552144] controller systemd-networkd[225]: eth1: Gained IPv6LL239peer1 # [6432039.808135] peer1 systemd-networkd[214]: eth1: Gained IPv6LL240controller: still waiting for container 'controller' to reach ready state...241peer1 # [6432048.985605] peer1 data-mesher[219]: time=2026-08-22T10:04:35.038Z level=INFO msg="performing state exchange with peers on join" count=1242peer1 # [6432048.985605] peer1 data-mesher[219]: time=2026-08-22T10:04:35.038Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s243peer1 # [6432048.986811] peer1 data-mesher[219]: time=2026-08-22T10:04:35.039Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH244peer1 # [6432048.986811] peer1 data-mesher[219]: time=2026-08-22T10:04:35.039Z level=INFO msg="state exchange complete" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s245peer1 # [6432048.986940] peer1 data-mesher[219]: time=2026-08-22T10:04:35.040Z level=INFO msg="server started"246peer1 # [6432048.987036] peer1 data-mesher[219]: time=2026-08-22T10:04:35.040Z level=INFO msg="starting expired-file sweeper" interval=1m0s247peer1 # [6432048.987456] peer1 systemd[1]: Started data mesher daemon.248peer1 # [6432048.989592] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...249peer1 # [6432048.990131] peer1 data-mesher[219]: time=2026-08-22T10:04:35.043Z level=INFO msg="received state sync from peer" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH250peer1 # [6432048.990131] peer1 data-mesher[219]: time=2026-08-22T10:04:35.043Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH251peer1 # [6432049.143404] peer1 data-mesher[219]: time=2026-08-22T10:04:35.196Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ status=204252peer1 # [6432049.143684] peer1 dm-wg-star-publish[284]: Status: 204 No Content253peer1 # [6432049.146210] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...254peer1 # [6432049.147643] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.255peer1 # [6432049.147930] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.256peer1 # [6432049.148956] peer1 systemd[1]: Reached target Multi-User System.257peer1 # [6432049.264563] peer1 dm-wg-star-reconfig[312]: No controller data available yet, skipping258controller # [6432048.986517] controller data-mesher[230]: time=2026-08-22T10:04:35.039Z level=INFO msg="received state sync from peer" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj259controller # [6432048.986517] controller data-mesher[230]: time=2026-08-22T10:04:35.039Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj260controller # [6432048.989304] controller data-mesher[230]: time=2026-08-22T10:04:35.042Z level=INFO msg="performing state exchange with peers on join" count=1261controller # [6432048.989384] controller data-mesher[230]: time=2026-08-22T10:04:35.042Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s262controller # [6432048.990241] controller data-mesher[230]: time=2026-08-22T10:04:35.043Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj263controller # [6432048.990241] controller data-mesher[230]: time=2026-08-22T10:04:35.043Z level=INFO msg="state exchange complete" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s264controller # [6432048.990354] controller data-mesher[230]: time=2026-08-22T10:04:35.043Z level=INFO msg="server started"265controller # [6432048.990490] controller data-mesher[230]: time=2026-08-22T10:04:35.043Z level=INFO msg="starting expired-file sweeper" interval=1m0s266controller # [6432048.990601] controller systemd[1]: Started data mesher daemon.267controller # [6432048.992642] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...268controller # [6432049.062595] controller data-mesher[230]: time=2026-08-22T10:04:35.115Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204269controller # [6432049.062957] controller dm-wg-star-publish[295]: Status: 204 No Content270controller # [6432049.065228] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...271controller # [6432049.066841] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.272controller # [6432049.067628] controller systemd[1]: Reached target Multi-User System.273controller # [6432049.160764] controller dm-wg-star-reconfig[321]: No peer data available yet, skipping274controller # [6432049.162112] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.275controller # [6432049.176334] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.276controller # [6432049.176959] controller systemd[1]: Startup finished in 11.741s.277controller: (finished: waiting for unit data-mesher.service, in 12.68 seconds)278peer1: waiting for unit data-mesher.service279peer1: (finished: waiting for unit data-mesher.service, in 0.02 seconds)280??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.281 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39282controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller283??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.284 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39285controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.01 seconds)286peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller287peer1 # [6432049.265864] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.288peer1 # [6432049.266009] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.289peer1 # [6432049.266624] peer1 systemd[1]: Startup finished in 11.842s.290peer1 # [6432053.988419] peer1 data-mesher[219]: time=2026-08-22T10:04:40.041Z level=DEBUG msg="attempting push/pull" peer_count=1291peer1 # [6432053.989279] peer1 data-mesher[219]: time=2026-08-22T10:04:40.041Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s292peer1 # [6432053.990084] peer1 data-mesher[219]: time=2026-08-22T10:04:40.043Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH293peer1 # [6432053.990169] peer1 data-mesher[219]: time=2026-08-22T10:04:40.043Z level=DEBUG msg="new file detected" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller294peer1 # [6432053.990169] peer1 data-mesher[219]: time=2026-08-22T10:04:40.043Z level=INFO msg="state exchange complete" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s295peer1 # [6432053.990262] peer1 data-mesher[219]: time=2026-08-22T10:04:40.043Z level=DEBUG msg="push/pull successful" interval=5s296peer1 # [6432053.990262] peer1 data-mesher[219]: time=2026-08-22T10:04:40.043Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller297peer1 # [6432053.990352] peer1 data-mesher[219]: time=2026-08-22T10:04:40.043Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-08-22 10:04:35.112 +0000 UTC" signed_by="hqTFZ8U0OafOaQNCkU8FmXMaaWtTJqiXDvmRbxYXunM=" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH298peer1 # [6432053.990727] peer1 data-mesher[219]: time=2026-08-22T10:04:40.043Z level=INFO msg="received file request" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ299peer1 # [6432053.993393] peer1 data-mesher[219]: time=2026-08-22T10:04:40.046Z level=INFO msg="file transfer complete" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ300peer1 # [6432053.993512] peer1 data-mesher[219]: time=2026-08-22T10:04:40.046Z level=INFO msg="received state sync from peer" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH301peer1 # [6432053.993560] peer1 data-mesher[219]: time=2026-08-22T10:04:40.046Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH302peer1 # [6432053.993588] peer1 data-mesher[219]: time=2026-08-22T10:04:40.046Z level=DEBUG msg="new file detected" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller303peer1 # [6432053.996197] peer1 data-mesher[219]: time=2026-08-22T10:04:40.049Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-08-22 10:04:35.112 +0000 UTC" signed_by="hqTFZ8U0OafOaQNCkU8FmXMaaWtTJqiXDvmRbxYXunM=" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH written=true elapsed=5.917802ms304peer1 # [6432053.998808] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...305peer1 # [6432054.144486] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.306peer1 # [6432054.144588] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.307controller # [6432053.989317] controller data-mesher[230]: time=2026-08-22T10:04:40.042Z level=INFO msg="received state sync from peer" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj308controller # [6432053.989317] controller data-mesher[230]: time=2026-08-22T10:04:40.042Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj309controller # [6432053.990068] controller data-mesher[230]: time=2026-08-22T10:04:40.042Z level=DEBUG msg="new file detected" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ310controller # [6432053.990068] controller data-mesher[230]: time=2026-08-22T10:04:40.043Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ311controller # [6432053.990068] controller data-mesher[230]: time=2026-08-22T10:04:40.043Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ signed_at="2026-08-22 10:04:35.19 +0000 UTC" signed_by="6P8Tv0aDptB569qnkfsO8IEIRjilS+TZfR4hkLsqRlQ=" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj312controller # [6432053.990790] controller data-mesher[230]: time=2026-08-22T10:04:40.043Z level=INFO msg="received file request" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/controller313controller # [6432053.990854] controller data-mesher[230]: time=2026-08-22T10:04:40.043Z level=DEBUG msg="attempting push/pull" peer_count=1314controller # [6432053.990854] controller data-mesher[230]: time=2026-08-22T10:04:40.044Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s315controller # [6432053.993521] controller data-mesher[230]: time=2026-08-22T10:04:40.046Z level=INFO msg="file transfer complete" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/controller316controller # [6432053.994119] controller data-mesher[230]: time=2026-08-22T10:04:40.047Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj317controller # [6432053.994716] controller data-mesher[230]: time=2026-08-22T10:04:40.047Z level=DEBUG msg="new file detected" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ318controller # [6432053.994783] controller data-mesher[230]: time=2026-08-22T10:04:40.047Z level=INFO msg="state exchange complete" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s319controller # [6432053.994783] controller data-mesher[230]: time=2026-08-22T10:04:40.047Z level=DEBUG msg="push/pull successful" interval=5s320controller # [6432053.996214] controller data-mesher[230]: time=2026-08-22T10:04:40.049Z level=INFO msg="download complete" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ signed_at="2026-08-22 10:04:35.19 +0000 UTC" signed_by="6P8Tv0aDptB569qnkfsO8IEIRjilS+TZfR4hkLsqRlQ=" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj written=true elapsed=6.187526ms321controller # [6432053.998750] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...322controller # [6432054.142852] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.323controller # [6432054.143158] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.324peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.05 seconds)325controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q .326controller: (finished: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q ., in 0.01 seconds)327controller: waiting for success: wg show wg-star peers | grep -q .328controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)329peer1: waiting for success: wg show wg-star peers | grep -q .330peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)331peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a47f:6a0a:e23c:6aec332peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a47f:6a0a:e23c:6aec, in 0.01 seconds)333controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bf54:ef5a:5618:ddb5334controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bf54:ef5a:5618:ddb5, in 0.01 seconds)335controller: must succeed: wg show wg-star peers | wc -l336controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.01 seconds)337peer2: systemd-nspawn running (pid 777)338peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...339peer2: waiting for unit data-mesher.service340nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE341nixos-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.342Note: 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.343░ Spawning container peer2 on /build/vm-state-peer2.344peer2 # [6432055.643184] peer2 systemd-journald[95]: Journal started345peer2 # [6432055.643240] peer2 systemd-journald[95]: Runtime Journal (/run/log/journal/cb5c6c11981544a68389545da69f009c) is 8M, max 2.5G, 2.4G free.346peer2 # [6432055.647229] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...347peer2 # [6432055.647933] peer2 systemd[1]: Starting Network Name Resolution...348peer2 # [6432055.648600] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...349peer2 # [6432055.658178] peer2 systemd-journald[95]: Time spent on flushing to /var/log/journal/cb5c6c11981544a68389545da69f009c is 1.685ms for 5 entries.350peer2 # [6432055.658178] peer2 systemd-journald[95]: System Journal (/var/log/journal/cb5c6c11981544a68389545da69f009c) is 8M, max 4G, 3.9G free.351peer2 # [6432055.664092] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.352peer2 # [6432055.664312] peer2 systemd[1]: Reached target Preparation for Local File Systems.353peer2 # [6432055.664394] peer2 systemd[1]: Reached target Local File Systems.354peer2 # [6432055.665108] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.355peer2 # [6432055.665150] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container356peer2 # [6432055.666268] peer2 systemd[1]: Starting Save Transient machine-id to Disk...357peer2 # [6432055.666300] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys358peer2 # [6432055.666730] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.359peer2 # [6432055.668045] peer2 systemd[1]: Starting Create System Files and Directories...360peer2 # [6432055.685513] peer2 systemd-tmpfiles[134]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted361peer2 # [6432055.685715] peer2 systemd-tmpfiles[134]: fchmod() of /var/log/journal failed: Operation not permitted362peer2 # [6432055.685856] peer2 systemd-tmpfiles[134]: fchmod() of /var/log/journal/cb5c6c11981544a68389545da69f009c failed: Operation not permitted363peer2 # [6432055.686064] peer2 systemd-tmpfiles[134]: fchmod() of /run/log/journal failed: Operation not permitted364peer2 # [6432055.687408] peer2 systemd[1]: Finished Create System Files and Directories.365peer2 # [6432055.688409] peer2 systemd[1]: Starting Rebuild Journal Catalog...366peer2 # [6432055.689095] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...367peer2 # [6432055.698724] peer2 systemd[1]: Finished Save Transient machine-id to Disk.368peer2 # [6432055.703522] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.369peer2 # [6432055.711525] peer2 systemd[1]: Finished Rebuild Journal Catalog.370peer2 # [6432055.713398] peer2 systemd[1]: Starting Update is Completed...371peer2 # [6432055.725548] peer2 systemd[1]: Finished Update is Completed.372peer2 # [6432055.794739] peer2 systemd[1]: Finished Firewall.373peer2 # [6432055.794826] peer2 systemd[1]: Reached target Preparation for Network.374peer2 # [6432055.795033] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.375peer2 # [6432055.796139] peer2 systemd[1]: Starting Network Management...376peer2 # [6432056.193497] peer2 systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted377peer2 # [6432056.193591] peer2 systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted378peer2 # [6432056.200763] peer2 systemd-networkd[213]: /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.379peer2 # [6432056.200924] peer2 systemd-networkd[213]: /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.380peer2 # [6432056.201075] peer2 systemd-networkd[213]: lo: Link UP381peer2 # [6432056.201080] peer2 systemd-networkd[213]: lo: Gained carrier382peer2 # [6432056.201262] peer2 systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.383peer2 # [6432056.201689] peer2 systemd[1]: Started Network Management.384peer2 # [6432056.202552] peer2 systemd-networkd[213]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.385peer2 # [6432056.202865] peer2 systemd-networkd[213]: wg-star: netdev ready386peer2 # [6432056.203260] peer2 systemd-networkd[213]: eth1: Link UP387peer2 # [6432056.203461] peer2 systemd-networkd[213]: eth1: Gained carrier388peer2 # [6432056.203960] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...389peer2 # [6432056.232390] peer2 systemd-resolved[116]: Positive Trust Anchors:390peer2 # [6432056.232401] peer2 systemd-resolved[116]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d391peer2 # [6432056.232404] peer2 systemd-resolved[116]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16392peer2 # [6432056.232439] peer2 systemd-resolved[116]: 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 test393peer2 # [6432056.232538] peer2 systemd-networkd[213]: wg-star: Link UP394peer2 # [6432056.232540] peer2 systemd-networkd[213]: wg-star: Gained carrier395peer2 # [6432056.254029] peer2 systemd-resolved[116]: Using system hostname 'peer2'.396peer2 # [6432056.255567] peer2 systemd[1]: Started Network Name Resolution.397peer2 # [6432056.255693] peer2 systemd[1]: Reached target Network.398peer2 # [6432056.255798] peer2 systemd[1]: Reached target System Initialization.399peer2 # [6432056.255950] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.400peer2 # [6432056.256017] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.401peer2 # [6432056.256061] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container402peer2 # [6432056.256103] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.403peer2 # [6432056.256144] peer2 systemd[1]: Reached target Path Units.404peer2 # [6432056.256211] peer2 systemd[1]: Reached target Timer Units.405peer2 # [6432056.256421] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.406peer2 # [6432056.256624] peer2 systemd[1]: Listening on Nix Daemon Socket.407peer2 # [6432056.256835] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.408peer2 # [6432056.256884] peer2 systemd[1]: Reached target Socket Units.409peer2 # [6432056.256961] peer2 systemd[1]: Reached target Basic System.410peer2 # [6432056.259021] peer2 systemd[1]: Starting data mesher daemon...411peer2 # [6432056.260363] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...412peer2 # [6432056.261736] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...413peer2 # [6432056.262187] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.414peer2 # [6432056.265477] peer2 systemd[1]: Starting D-Bus System Message Bus...415peer2 # [6432056.282257] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.416peer2 # [6432056.362561] peer2 nsncd[219]: Aug 22 10:04:42.415 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"417peer2 # [6432056.362586] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).418peer2 # [6432056.362688] peer2 systemd[1]: Reached target Host and Network Name Lookups.419peer2 # [6432056.362786] peer2 systemd[1]: Reached target User and Group Name Lookups.420peer2 # [6432056.364841] peer2 systemd[1]: Starting User Login Management...421peer2 # [6432056.366140] peer2 systemd[1]: Starting Permit User Sessions...422peer2 # [6432056.399854] peer2 systemd[1]: Finished Permit User Sessions.423peer2 # [6432056.401556] peer2 systemd[1]: Started Console Getty.424peer2 # [6432056.401639] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0425peer2 # [6432056.401685] peer2 systemd[1]: Reached target Login Prompts.426peer2 # [6432056.438580] peer2 dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...427peer2 # [6432056.440011] peer2 dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'428peer2 # [6432056.440011] peer2 dbus-broker-launch[221]: Invalid user-name in /nix/store/z2330ck6iq53jg1pf081mhmw858hsszq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"429peer2 # [6432056.440471] peer2 systemd[1]: Started D-Bus System Message Bus.430peer2 # [6432056.449282] peer2 dbus-broker-launch[221]: Ready431peer2 # [6432056.634992] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.432peer2 # [6432056.662855] peer2 data-mesher[217]: time=2026-08-22T10:04:42.715Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]433peer2 # [6432056.663922] peer2 data-mesher[217]: time=2026-08-22T10:04:42.717Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH: [/dns/controller.clan/tcp/7946]} {12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj: [/dns/peer1.clan/tcp/7946]} {12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8434peer2 # [6432056.663922] peer2 data-mesher[217]: time=2026-08-22T10:04:42.717Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml435peer2 # [6432056.667271] peer2 data-mesher[217]: time=2026-08-22T10:04:42.720Z level=INFO msg="checking file integrity"436peer2 # [6432056.667424] peer2 data-mesher[217]: time=2026-08-22T10:04:42.720Z level=INFO msg="file integrity check complete"437peer2 # [6432056.673261] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="libp2p host created" peer_id=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.3/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::3/tcp/7946 /ip6/fda1:5c8::dd7f:2956:398a:98/tcp/7946]"438peer2 # [6432056.673309] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="registered HTTP route" method=GET path=/files439peer2 # [6432056.673309] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name440peer2 # [6432056.673367] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name441peer2 # [6432056.673367] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="starting server"442peer2 # [6432056.673473] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="waiting for DHT to populate" delay=10s443peer2 # [6432056.673507] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="HTTP server listening" address=[::1]:7331444peer2 # [6432056.673555] peer2 data-mesher[217]: time=2026-08-22T10:04:42.726Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331445peer2 # [6432056.678878] peer2 data-mesher[217]: time=2026-08-22T10:04:42.732Z level=INFO msg="peer connected" peer_id=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj remote_addr=/ip4/192.168.1.2/tcp/7946446peer2 # [6432056.688431] peer2 data-mesher[217]: time=2026-08-22T10:04:42.741Z level=INFO msg="peer connected" peer_id=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH remote_addr=/ip4/192.168.1.1/tcp/7946447peer2 # [6432056.811487] peer2 systemd-logind[238]: New seat seat0.448controller # [6432056.689866] controller data-mesher[230]: time=2026-08-22T10:04:42.742Z level=INFO msg="peer connected" peer_id=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 remote_addr=/ip4/192.168.1.3/tcp/7946449peer2 # [6432056.811700] peer2 systemd[1]: Started User Login Management.450peer2 # [6432056.812966] peer2 systemd[1]: Starting linger-users.service...451peer2 # [6432056.862126] peer2 systemd[1]: linger-users.service: Deactivated successfully.452peer2 # [6432056.862357] peer2 systemd[1]: Finished linger-users.service.453peer1 # [6432056.680609] peer1 data-mesher[219]: time=2026-08-22T10:04:42.733Z level=INFO msg="peer connected" peer_id=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 remote_addr=/ip4/192.168.1.3/tcp/7946454peer2 # [6432057.568367] peer2 systemd-networkd[213]: eth1: Gained IPv6LL455peer2 # [6432058.991542] peer2 data-mesher[217]: time=2026-08-22T10:04:45.044Z level=INFO msg="received state sync from peer" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj456peer2 # [6432058.991542] peer2 data-mesher[217]: time=2026-08-22T10:04:45.044Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj457peer2 # [6432058.992295] peer2 data-mesher[217]: time=2026-08-22T10:04:45.045Z level=DEBUG msg="new file detected" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ458peer2 # [6432058.992295] peer2 data-mesher[217]: time=2026-08-22T10:04:45.045Z level=DEBUG msg="new file detected" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller459peer2 # [6432058.992295] peer2 data-mesher[217]: time=2026-08-22T10:04:45.045Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ460peer2 # [6432058.992295] peer2 data-mesher[217]: time=2026-08-22T10:04:45.045Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller461peer2 # [6432058.992498] peer2 data-mesher[217]: time=2026-08-22T10:04:45.045Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-08-22 10:04:35.112 +0000 UTC" signed_by="hqTFZ8U0OafOaQNCkU8FmXMaaWtTJqiXDvmRbxYXunM=" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj462peer2 # [6432058.992498] peer2 data-mesher[217]: time=2026-08-22T10:04:45.045Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ signed_at="2026-08-22 10:04:35.19 +0000 UTC" signed_by="6P8Tv0aDptB569qnkfsO8IEIRjilS+TZfR4hkLsqRlQ=" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj463peer2 # [6432058.996254] peer2 data-mesher[217]: time=2026-08-22T10:04:45.049Z level=INFO msg="received state sync from peer" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH464peer2 # [6432058.996254] peer2 data-mesher[217]: time=2026-08-22T10:04:45.049Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH465peer2 # [6432058.996871] peer2 data-mesher[217]: time=2026-08-22T10:04:45.050Z level=DEBUG msg="new file detected" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ466peer2 # [6432058.996944] peer2 data-mesher[217]: time=2026-08-22T10:04:45.050Z level=DEBUG msg="new file detected" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller467peer2 # [6432058.998230] peer2 data-mesher[217]: time=2026-08-22T10:04:45.051Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-08-22 10:04:35.112 +0000 UTC" signed_by="hqTFZ8U0OafOaQNCkU8FmXMaaWtTJqiXDvmRbxYXunM=" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj written=true elapsed=5.940122ms468peer2 # [6432058.999246] peer2 data-mesher[217]: time=2026-08-22T10:04:45.052Z level=INFO msg="download complete" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ signed_at="2026-08-22 10:04:35.19 +0000 UTC" signed_by="6P8Tv0aDptB569qnkfsO8IEIRjilS+TZfR4hkLsqRlQ=" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj written=true elapsed=6.897455ms469peer2 # [6432059.000649] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...470peer2 # [6432059.156996] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.471peer2 # [6432059.157297] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.472peer1 # [6432058.990648] peer1 data-mesher[219]: time=2026-08-22T10:04:45.043Z level=DEBUG msg="attempting push/pull" peer_count=1473peer1 # [6432058.991243] peer1 data-mesher[219]: time=2026-08-22T10:04:45.043Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s474peer1 # [6432058.992347] peer1 data-mesher[219]: time=2026-08-22T10:04:45.045Z level=INFO msg="merging remote state" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8475peer1 # [6432058.992347] peer1 data-mesher[219]: time=2026-08-22T10:04:45.045Z level=INFO msg="state exchange complete" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s476peer1 # [6432058.992474] peer1 data-mesher[219]: time=2026-08-22T10:04:45.045Z level=DEBUG msg="push/pull successful" interval=5s477peer1 # [6432058.993076] peer1 data-mesher[219]: time=2026-08-22T10:04:45.046Z level=INFO msg="received file request" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ478peer1 # [6432058.993141] peer1 data-mesher[219]: time=2026-08-22T10:04:45.046Z level=INFO msg="received file request" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/controller479peer1 # [6432058.994305] peer1 data-mesher[219]: time=2026-08-22T10:04:45.047Z level=INFO msg="file transfer complete" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/controller480peer1 # [6432058.996154] peer1 data-mesher[219]: time=2026-08-22T10:04:45.049Z level=INFO msg="file transfer complete" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ481controller # [6432058.995579] controller data-mesher[230]: time=2026-08-22T10:04:45.048Z level=DEBUG msg="attempting push/pull" peer_count=1482controller # [6432058.996267] controller data-mesher[230]: time=2026-08-22T10:04:45.048Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s483controller # [6432058.997160] controller data-mesher[230]: time=2026-08-22T10:04:45.050Z level=INFO msg="merging remote state" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8484controller # [6432058.997160] controller data-mesher[230]: time=2026-08-22T10:04:45.050Z level=INFO msg="state exchange complete" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s485controller # [6432058.997283] controller data-mesher[230]: time=2026-08-22T10:04:45.050Z level=DEBUG msg="push/pull successful" interval=5s486peer2 # [6432063.993999] peer2 data-mesher[217]: time=2026-08-22T10:04:50.047Z level=INFO msg="received state sync from peer" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj487peer2 # [6432063.993999] peer2 data-mesher[217]: time=2026-08-22T10:04:50.047Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj488peer1 # [6432063.993202] peer1 data-mesher[219]: time=2026-08-22T10:04:50.046Z level=DEBUG msg="attempting push/pull" peer_count=1489peer1 # [6432063.993907] peer1 data-mesher[219]: time=2026-08-22T10:04:50.046Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s490peer1 # [6432063.995049] peer1 data-mesher[219]: time=2026-08-22T10:04:50.048Z level=INFO msg="merging remote state" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8491peer1 # [6432063.995676] peer1 data-mesher[219]: time=2026-08-22T10:04:50.048Z level=INFO msg="state exchange complete" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s492peer1 # [6432063.995757] peer1 data-mesher[219]: time=2026-08-22T10:04:50.048Z level=DEBUG msg="push/pull successful" interval=5s493peer1 # [6432063.998166] peer1 data-mesher[219]: time=2026-08-22T10:04:50.051Z level=INFO msg="received state sync from peer" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH494peer1 # [6432063.998250] peer1 data-mesher[219]: time=2026-08-22T10:04:50.051Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH495controller # [6432063.997556] controller data-mesher[230]: time=2026-08-22T10:04:50.050Z level=DEBUG msg="attempting push/pull" peer_count=1496controller # [6432063.997556] controller data-mesher[230]: time=2026-08-22T10:04:50.050Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s497controller # [6432063.999109] controller data-mesher[230]: time=2026-08-22T10:04:50.052Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj498controller # [6432063.999681] controller data-mesher[230]: time=2026-08-22T10:04:50.052Z level=INFO msg="state exchange complete" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s499controller # [6432063.999752] controller data-mesher[230]: time=2026-08-22T10:04:50.052Z level=DEBUG msg="push/pull successful" interval=5s500peer2: still waiting for container 'peer2' to reach ready state...501controller # [6432066.675006] controller data-mesher[230]: time=2026-08-22T10:04:52.728Z level=INFO msg="received state sync from peer" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8502controller # [6432066.675006] controller data-mesher[230]: time=2026-08-22T10:04:52.728Z level=INFO msg="merging remote state" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8503peer2 # [6432066.674143] peer2 data-mesher[217]: time=2026-08-22T10:04:52.727Z level=INFO msg="performing state exchange with peers on join" count=1504peer2 # [6432066.674143] peer2 data-mesher[217]: time=2026-08-22T10:04:52.727Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s505peer2 # [6432066.675931] peer2 data-mesher[217]: time=2026-08-22T10:04:52.729Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH506peer2 # [6432066.676538] peer2 data-mesher[217]: time=2026-08-22T10:04:52.729Z level=INFO msg="state exchange complete" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s507peer2 # [6432066.676624] peer2 data-mesher[217]: time=2026-08-22T10:04:52.729Z level=INFO msg="server started"508peer2 # [6432066.676765] peer2 data-mesher[217]: time=2026-08-22T10:04:52.729Z level=INFO msg="starting expired-file sweeper" interval=1m0s509peer2 # [6432066.676936] peer2 systemd[1]: Started data mesher daemon.510peer2 # [6432066.679153] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...511peer2 # [6432066.837667] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...512peer2 # [6432066.840410] peer2 data-mesher[217]: time=2026-08-22T10:04:52.893Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk status=204513peer2 # [6432066.840605] peer2 dm-wg-star-publish[294]: Status: 204 No Content514peer2 # [6432066.843989] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.515peer2 # [6432066.884247] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.516peer2 # [6432066.884824] peer2 systemd[1]: Reached target Multi-User System.517peer2 # [6432066.997363] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.518peer2 # [6432066.997663] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.519peer2 # [6432066.998317] peer2 systemd[1]: Startup finished in 11.786s.520peer2: (finished: waiting for unit data-mesher.service, in 12.67 seconds)521??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.522 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39523controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1524??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.525 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39526peer2 # [6432069.000552] peer2 data-mesher[217]: time=2026-08-22T10:04:55.053Z level=INFO msg="received state sync from peer" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH527peer2 # [6432069.000552] peer2 data-mesher[217]: time=2026-08-22T10:04:55.053Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH528peer2 # [6432069.001763] peer2 data-mesher[217]: time=2026-08-22T10:04:55.054Z level=INFO msg="received file request" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk529peer2 # [6432069.003687] peer2 data-mesher[217]: time=2026-08-22T10:04:55.056Z level=INFO msg="file transfer complete" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk530peer1 # [6432068.996585] peer1 data-mesher[219]: time=2026-08-22T10:04:55.049Z level=DEBUG msg="attempting push/pull" peer_count=1531peer1 # [6432068.996585] peer1 data-mesher[219]: time=2026-08-22T10:04:55.049Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s532peer1 # [6432068.997616] peer1 data-mesher[219]: time=2026-08-22T10:04:55.050Z level=INFO msg="merging remote state" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH533peer1 # [6432068.997833] peer1 data-mesher[219]: time=2026-08-22T10:04:55.051Z level=INFO msg="state exchange complete" peer=12D3KooWMDXTm5A4WCd2aL3WuYMrQrr75LnULSyRH1EGtvbxxAuH timeout=5s534peer1 # [6432068.997863] peer1 data-mesher[219]: time=2026-08-22T10:04:55.051Z level=DEBUG msg="push/pull successful" interval=5s535controller: (finished: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1, in 2.04 seconds)536controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1537controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.02 seconds)538peer2: waiting for success: wg show wg-star peers | grep -q .539peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)540peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a47f:6a0a:e23c:6aec541peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a47f:6a0a:e23c:6aec, in 0.01 seconds)542controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::dd7f:2956:398a:0098543controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::dd7f:2956:398a:0098, in 0.01 seconds)544peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::dd7f:2956:398a:0098545peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::dd7f:2956:398a:0098, in 0.01 seconds)546peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bf54:ef5a:5618:ddb5547controller # [6432068.997534] controller data-mesher[230]: time=2026-08-22T10:04:55.050Z level=INFO msg="received state sync from peer" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj548controller # [6432068.997534] controller data-mesher[230]: time=2026-08-22T10:04:55.050Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj549controller # [6432069.000129] controller data-mesher[230]: time=2026-08-22T10:04:55.053Z level=DEBUG msg="attempting push/pull" peer_count=1550controller # [6432069.000234] controller data-mesher[230]: time=2026-08-22T10:04:55.053Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s551controller # [6432069.000964] controller data-mesher[230]: time=2026-08-22T10:04:55.054Z level=INFO msg="merging remote state" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8552controller # [6432069.001374] controller data-mesher[230]: time=2026-08-22T10:04:55.054Z level=DEBUG msg="new file detected" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk553controller # [6432069.001456] controller data-mesher[230]: time=2026-08-22T10:04:55.054Z level=INFO msg="state exchange complete" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 timeout=5s554controller # [6432069.001456] controller data-mesher[230]: time=2026-08-22T10:04:55.054Z level=DEBUG msg="push/pull successful" interval=5s555controller # [6432069.001495] controller data-mesher[230]: time=2026-08-22T10:04:55.054Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk556controller # [6432069.001525] controller data-mesher[230]: time=2026-08-22T10:04:55.054Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk signed_at="2026-08-22 10:04:52.886 +0000 UTC" signed_by="EBmVhTTtUkumXOF/+9mhYPkjkkBu++i1GNDHc5JI8yk=" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8557controller # [6432069.006749] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...558controller # [6432069.009324] controller data-mesher[230]: time=2026-08-22T10:04:55.062Z level=INFO msg="download complete" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk signed_at="2026-08-22 10:04:52.886 +0000 UTC" signed_by="EBmVhTTtUkumXOF/+9mhYPkjkkBu++i1GNDHc5JI8yk=" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 written=true elapsed=7.817229ms559controller # [6432069.165169] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.560controller # [6432069.165395] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.561peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bf54:ef5a:5618:ddb5, in 0.01 seconds)562(finished: run the VM test script, in 32.61 seconds)563peer2 # [6432071.677046] peer2 data-mesher[217]: time=2026-08-22T10:04:57.730Z level=DEBUG msg="attempting push/pull" peer_count=2564peer2 # [6432071.677046] peer2 data-mesher[217]: time=2026-08-22T10:04:57.730Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s565peer2 # [6432071.678651] peer2 data-mesher[217]: time=2026-08-22T10:04:57.731Z level=INFO msg="merging remote state" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj566peer2 # [6432071.678759] peer2 data-mesher[217]: time=2026-08-22T10:04:57.731Z level=INFO msg="received file request" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk567peer2 # [6432071.678881] peer2 data-mesher[217]: time=2026-08-22T10:04:57.732Z level=INFO msg="state exchange complete" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj timeout=5s568peer2 # [6432071.678915] peer2 data-mesher[217]: time=2026-08-22T10:04:57.732Z level=DEBUG msg="push/pull successful" interval=5s569peer2 # [6432071.680650] peer2 data-mesher[217]: time=2026-08-22T10:04:57.733Z level=INFO msg="file transfer complete" peer=12D3KooWRVtM2tH81LooxiGQcCiV6vZpEqCgZvUKrVpnxgwYCkxj network="ob4X/jrwGFd6Fhw1K8c5oFrWGhDcADPSMtrOQ2+e6kE=" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk570peer1 # [6432071.677752] peer1 data-mesher[219]: time=2026-08-22T10:04:57.730Z level=INFO msg="received state sync from peer" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8571peer1 # [6432071.677752] peer1 data-mesher[219]: time=2026-08-22T10:04:57.730Z level=INFO msg="merging remote state" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8572peer1 # [6432071.678185] peer1 data-mesher[219]: time=2026-08-22T10:04:57.731Z level=DEBUG msg="new file detected" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 name=dm_wg_star_wg_star/6P8Tv0aDptB569qnkfsO8IEIRjilS-TZfR4hkLsqRlQ name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk573peer1 # [6432071.678263] peer1 data-mesher[219]: time=2026-08-22T10:04:57.731Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk574peer1 # [6432071.678293] peer1 data-mesher[219]: time=2026-08-22T10:04:57.731Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk signed_at="2026-08-22 10:04:52.886 +0000 UTC" signed_by="EBmVhTTtUkumXOF/+9mhYPkjkkBu++i1GNDHc5JI8yk=" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8575peer1 # [6432071.684266] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...576peer1 # [6432071.706974] peer1 data-mesher[219]: time=2026-08-22T10:04:57.760Z level=INFO msg="download complete" name=dm_wg_star_wg_star/EBmVhTTtUkumXOF_-9mhYPkjkkBu--i1GNDHc5JI8yk signed_at="2026-08-22 10:04:52.886 +0000 UTC" signed_by="EBmVhTTtUkumXOF/+9mhYPkjkkBu++i1GNDHc5JI8yk=" peer=12D3KooWAuDKSC9aQxvqJw6ZX5gUMk41Y2RkqacDqyL87cz4iLC8 written=true elapsed=28.659157ms577peer1 # [6432071.864443] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.578peer1 # [6432071.864634] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.579test script finished in 35.50s580cleanup581kill NspawnMachine (pid 50)582kill NspawnMachine (pid 53)583Container controller terminated by signal KILL.584kill NspawnMachine (pid 777)585Container peer1 terminated by signal KILL.586Container peer2 terminated by signal KILL.587(finished: cleanup, in 0.69 seconds)