container-test-run-dm-wireguard-star
checks.aarch64-linux.dm-wireguard-star
· build #49
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.01 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(peer1): TAP vde-tap1 not found; container will be isolated from VDE17nixos-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.18nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE19nixos-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.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 peer1 on /build/vm-state-peer1.23░ Spawning container controller on /build/vm-state-controller.24controller # No journal files were found.25controller # No journal boot entry found for the specified boot (+0).26peer1 # No journal files were found.27peer1 # No journal boot entry found for the specified boot (+0).28controller # [6519693.336541] controller systemd-journald[105]: Journal started29controller # [6519693.336598] controller systemd-journald[105]: Runtime Journal (/run/log/journal/eae30a5693904201b523367cb8b53b63) is 8M, max 2.5G, 2.4G free.30controller # [6519693.340425] controller systemd[1]: Starting Flush Journal to Persistent Storage...31controller # [6519693.341205] controller systemd[1]: Starting Network Name Resolution...32controller # [6519693.341917] controller systemd[1]: Starting Create Static Device Nodes in /dev...33controller # [6519693.350907] controller systemd-journald[105]: Time spent on flushing to /var/log/journal/eae30a5693904201b523367cb8b53b63 is 1.372ms for 5 entries.34controller # [6519693.350907] controller systemd-journald[105]: System Journal (/var/log/journal/eae30a5693904201b523367cb8b53b63) is 8M, max 4G, 3.9G free.35controller # [6519693.358343] controller systemd[1]: Finished Create Static Device Nodes in /dev.36controller # [6519693.358559] controller systemd[1]: Finished Flush Journal to Persistent Storage.37controller # [6519693.359170] controller systemd[1]: Reached target Preparation for Local File Systems.38controller # [6519693.359295] controller systemd[1]: Reached target Local File Systems.39controller # [6519693.360115] controller systemd[1]: Listening on Boot Loader Control Service Socket.40controller # [6519693.360161] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container41controller # [6519693.360943] controller systemd[1]: Starting Save Transient machine-id to Disk...42controller # [6519693.361817] controller systemd[1]: Starting Create System Files and Directories...43controller # [6519693.361845] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys44controller # [6519693.378605] controller systemd-tmpfiles[143]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted45controller # [6519693.378835] controller systemd-tmpfiles[143]: fchmod() of /var/log/journal failed: Operation not permitted46controller # [6519693.378993] controller systemd-tmpfiles[143]: fchmod() of /var/log/journal/eae30a5693904201b523367cb8b53b63 failed: Operation not permitted47controller # [6519693.379240] controller systemd-tmpfiles[143]: fchmod() of /run/log/journal failed: Operation not permitted48controller # [6519693.380853] controller systemd[1]: Finished Create System Files and Directories.49controller # [6519693.381993] controller systemd[1]: Starting Rebuild Journal Catalog...50controller # [6519693.382739] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...51controller # [6519693.395592] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.52controller # [6519693.406655] controller systemd[1]: Finished Rebuild Journal Catalog.53controller # [6519693.407603] controller systemd[1]: Starting Update is Completed...54controller # [6519693.418751] controller systemd[1]: Finished Update is Completed.55controller # [6519693.491345] controller systemd[1]: Finished Firewall.56controller # [6519693.491489] controller systemd[1]: Reached target Preparation for Network.57controller # [6519693.491686] controller systemd[1]: Listening on Network Management Resolve Hook Socket.58controller # [6519693.492628] controller systemd[1]: Starting Network Management...59controller # [6519693.890170] controller systemd[1]: Finished Save Transient machine-id to Disk.60controller # [6519693.908142] controller systemd-networkd[224]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted61controller # [6519693.908228] controller systemd-networkd[224]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted62controller # [6519693.915363] controller systemd-networkd[224]: /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.63controller # [6519693.915529] controller systemd-networkd[224]: /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.64controller # [6519693.915669] controller systemd-networkd[224]: lo: Link UP65controller # [6519693.915672] controller systemd-networkd[224]: lo: Gained carrier66controller # [6519693.915838] controller systemd-networkd[224]: eth1: Configuring with /etc/systemd/network/40-eth1.network.67controller # [6519693.916237] controller systemd[1]: Started Network Management.68controller # [6519693.916933] controller systemd-networkd[224]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.69controller # [6519693.917247] controller systemd-networkd[224]: wg-star: netdev ready70controller # [6519693.917619] controller systemd-networkd[224]: eth1: Link UP71controller # [6519693.917825] controller systemd-networkd[224]: eth1: Gained carrier72controller # [6519693.917894] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...73controller # [6519693.952916] controller systemd-networkd[224]: wg-star: Link UP74controller # [6519693.952923] controller systemd-networkd[224]: wg-star: Gained carrier75controller # [6519693.954396] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.76controller # [6519693.976227] controller systemd-resolved[125]: Positive Trust Anchors:77controller # [6519693.976238] controller systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d78controller # [6519693.976241] controller systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b1679controller # [6519693.976276] 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 test80controller # [6519693.998237] controller systemd-resolved[125]: Using system hostname 'controller'.81controller # [6519693.999799] controller systemd[1]: Started Network Name Resolution.82controller # [6519693.999941] controller systemd[1]: Reached target Network.83controller # [6519694.000068] controller systemd[1]: Reached target System Initialization.84controller # [6519694.000238] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.85controller # [6519694.000291] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container86controller # [6519694.000341] controller systemd[1]: Started Daily Cleanup of Temporary Directories.87controller # [6519694.000383] controller systemd[1]: Reached target Path Units.88controller # [6519694.000451] controller systemd[1]: Reached target Timer Units.89controller # [6519694.000812] controller systemd[1]: Listening on D-Bus System Message Bus Socket.90controller # [6519694.001019] controller systemd[1]: Listening on Nix Daemon Socket.91controller # [6519694.001239] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.92controller # [6519694.001286] controller systemd[1]: Reached target Socket Units.93controller # [6519694.001363] controller systemd[1]: Reached target Basic System.94controller # [6519694.020569] controller systemd[1]: Starting data mesher daemon...95controller # [6519694.022006] controller systemd[1]: Starting Import lastlog data into lastlog2 database...96controller # [6519694.023377] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...97controller # [6519694.025508] controller systemd[1]: Starting D-Bus System Message Bus...98controller # [6519694.043237] controller systemd[1]: Finished Import lastlog data into lastlog2 database.99controller # [6519694.124407] controller systemd[1]: Started Name Service Cache Daemon (nsncd).100controller # [6519694.124473] controller systemd[1]: Reached target Host and Network Name Lookups.101controller # [6519694.124538] controller systemd[1]: Reached target User and Group Name Lookups.102controller # [6519694.124710] controller nsncd[232]: Aug 23 10:25:20.177 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"103controller # [6519694.141096] controller systemd[1]: Starting User Login Management...104controller # [6519694.142276] controller systemd[1]: Starting Permit User Sessions...105controller # [6519694.153174] controller systemd[1]: Finished Permit User Sessions.106controller # [6519694.155532] controller systemd[1]: Started Console Getty.107controller # [6519694.155580] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0108peer1 # [6519693.336547] peer1 systemd-journald[96]: Journal started109controller # [6519694.155602] controller systemd[1]: Reached target Login Prompts.110controller # [6519694.219173] controller dbus-broker-launch[233]: Looking up NSS user entry for 'systemd-timesync'...111peer1 # [6519693.336599] peer1 systemd-journald[96]: Runtime Journal (/run/log/journal/c7ec6803e1d54a8b95cc123daf74a11a) is 8M, max 2.5G, 2.4G free.112controller # [6519694.220601] controller dbus-broker-launch[233]: NSS returned no entry for 'systemd-timesync'113controller # [6519694.220601] 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"114controller # [6519694.220994] controller systemd[1]: Started D-Bus System Message Bus.115peer1 # [6519693.340522] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...116controller # [6519694.229016] controller dbus-broker-launch[233]: Ready117peer1 # [6519693.341215] peer1 systemd[1]: Starting Network Name Resolution...118controller # [6519694.328505] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.119peer1 # [6519693.341879] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...120peer1 # [6519693.351340] peer1 systemd-journald[96]: Time spent on flushing to /var/log/journal/c7ec6803e1d54a8b95cc123daf74a11a is 1.552ms for 5 entries.121peer1 # [6519693.351340] peer1 systemd-journald[96]: System Journal (/var/log/journal/c7ec6803e1d54a8b95cc123daf74a11a) is 8M, max 4G, 3.9G free.122peer1 # [6519693.358313] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.123peer1 # [6519693.358545] peer1 systemd[1]: Reached target Preparation for Local File Systems.124peer1 # [6519693.358624] peer1 systemd[1]: Reached target Local File Systems.125peer1 # [6519693.359330] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.126peer1 # [6519693.359375] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container127peer1 # [6519693.360245] peer1 systemd[1]: Starting Save Transient machine-id to Disk...128peer1 # [6519693.360280] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys129peer1 # [6519693.365492] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.130peer1 # [6519693.366274] peer1 systemd[1]: Starting Create System Files and Directories...131peer1 # [6519693.381385] peer1 systemd-tmpfiles[137]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted132peer1 # [6519693.381565] peer1 systemd-tmpfiles[137]: fchmod() of /var/log/journal failed: Operation not permitted133peer1 # [6519693.381691] peer1 systemd-tmpfiles[137]: fchmod() of /var/log/journal/c7ec6803e1d54a8b95cc123daf74a11a failed: Operation not permitted134peer1 # [6519693.381881] peer1 systemd-tmpfiles[137]: fchmod() of /run/log/journal failed: Operation not permitted135peer1 # [6519693.383171] peer1 systemd[1]: Finished Create System Files and Directories.136peer1 # [6519693.384169] peer1 systemd[1]: Starting Rebuild Journal Catalog...137peer1 # [6519693.384837] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...138peer1 # [6519693.397836] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.139peer1 # [6519693.405061] peer1 systemd[1]: Finished Rebuild Journal Catalog.140peer1 # [6519693.406067] peer1 systemd[1]: Starting Update is Completed...141peer1 # [6519693.417662] peer1 systemd[1]: Finished Update is Completed.142peer1 # [6519693.486928] peer1 systemd[1]: Finished Firewall.143peer1 # [6519693.487076] peer1 systemd[1]: Reached target Preparation for Network.144peer1 # [6519693.487284] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.145peer1 # [6519693.488306] peer1 systemd[1]: Starting Network Management...146peer1 # [6519693.889969] peer1 systemd[1]: Finished Save Transient machine-id to Disk.147peer1 # [6519693.890227] peer1 systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted148peer1 # [6519693.890327] peer1 systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted149peer1 # [6519693.898572] peer1 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.150peer1 # [6519693.898732] peer1 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.151peer1 # [6519693.898888] peer1 systemd-networkd[213]: lo: Link UP152peer1 # [6519693.898892] peer1 systemd-networkd[213]: lo: Gained carrier153peer1 # [6519693.899088] peer1 systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.154peer1 # [6519693.899472] peer1 systemd[1]: Started Network Management.155peer1 # [6519693.900009] peer1 systemd-networkd[213]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.156peer1 # [6519693.900500] peer1 systemd-networkd[213]: wg-star: netdev ready157peer1 # [6519693.900896] peer1 systemd-networkd[213]: eth1: Link UP158peer1 # [6519693.901106] peer1 systemd-networkd[213]: eth1: Gained carrier159peer1 # [6519693.901218] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...160peer1 # [6519693.932957] peer1 systemd-networkd[213]: wg-star: Link UP161peer1 # [6519693.932962] peer1 systemd-networkd[213]: wg-star: Gained carrier162peer1 # [6519693.933448] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.163peer1 # [6519693.960563] peer1 systemd-resolved[117]: Positive Trust Anchors:164peer1 # [6519693.960575] peer1 systemd-resolved[117]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d165peer1 # [6519693.960578] peer1 systemd-resolved[117]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16166peer1 # [6519693.960614] peer1 systemd-resolved[117]: 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 test167peer1 # [6519693.982390] peer1 systemd-resolved[117]: Using system hostname 'peer1'.168peer1 # [6519693.983935] peer1 systemd[1]: Started Network Name Resolution.169peer1 # [6519693.984032] peer1 systemd[1]: Reached target Network.170peer1 # [6519693.984109] peer1 systemd[1]: Reached target System Initialization.171peer1 # [6519693.984208] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.172peer1 # [6519693.984243] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.173peer1 # [6519693.984276] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container174peer1 # [6519693.984301] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.175peer1 # [6519693.984324] peer1 systemd[1]: Reached target Path Units.176peer1 # [6519693.984364] peer1 systemd[1]: Reached target Timer Units.177peer1 # [6519693.984499] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.178peer1 # [6519693.984623] peer1 systemd[1]: Listening on Nix Daemon Socket.179peer1 # [6519693.984747] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.180peer1 # [6519693.984775] peer1 systemd[1]: Reached target Socket Units.181peer1 # [6519693.984826] peer1 systemd[1]: Reached target Basic System.182peer1 # [6519693.986277] peer1 systemd[1]: Starting data mesher daemon...183peer1 # [6519693.987266] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...184peer1 # [6519693.988256] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...185peer1 # [6519693.990502] peer1 systemd[1]: Starting D-Bus System Message Bus...186peer1 # [6519694.037483] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.187peer1 # [6519694.146130] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).188peer1 # [6519694.146313] peer1 nsncd[221]: Aug 23 10:25:20.199 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"189peer1 # [6519694.146181] peer1 systemd[1]: Reached target Host and Network Name Lookups.190peer1 # [6519694.146232] peer1 systemd[1]: Reached target User and Group Name Lookups.191peer1 # [6519694.147394] peer1 systemd[1]: Starting User Login Management...192peer1 # [6519694.148262] peer1 systemd[1]: Starting Permit User Sessions...193peer1 # [6519694.158975] peer1 systemd[1]: Finished Permit User Sessions.194peer1 # [6519694.160371] peer1 systemd[1]: Started Console Getty.195peer1 # [6519694.160425] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0196peer1 # [6519694.160448] peer1 systemd[1]: Reached target Login Prompts.197peer1 # [6519694.223659] peer1 dbus-broker-launch[222]: Looking up NSS user entry for 'systemd-timesync'...198peer1 # [6519694.224606] peer1 dbus-broker-launch[222]: NSS returned no entry for 'systemd-timesync'199peer1 # [6519694.224606] 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"200peer1 # [6519694.225253] peer1 systemd[1]: Started D-Bus System Message Bus.201peer1 # [6519694.234649] peer1 dbus-broker-launch[222]: Ready202peer1 # [6519694.320809] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.203peer1 # [6519694.483291] peer1 data-mesher[219]: time=2026-08-23T10:25:20.536Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]204peer1 # [6519694.484325] peer1 data-mesher[219]: time=2026-08-23T10:25:20.537Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf: [/dns/controller.clan/tcp/7946]} {12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC: [/dns/peer1.clan/tcp/7946]} {12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC205peer1 # [6519694.484325] peer1 data-mesher[219]: time=2026-08-23T10:25:20.537Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml206peer1 # [6519694.505554] peer1 data-mesher[219]: time=2026-08-23T10:25:20.558Z level=INFO msg="checking file integrity"207peer1 # [6519694.505653] peer1 data-mesher[219]: time=2026-08-23T10:25:20.558Z level=INFO msg="file integrity check complete"208peer1 # [6519694.509491] peer1 data-mesher[219]: time=2026-08-23T10:25:20.562Z level=INFO msg="libp2p host created" peer_id=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC 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::eac1:e45:4302:cfcf/tcp/7946]"209peer1 # [6519694.509565] peer1 data-mesher[219]: time=2026-08-23T10:25:20.562Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name210peer1 # [6519694.509565] peer1 data-mesher[219]: time=2026-08-23T10:25:20.562Z level=INFO msg="registered HTTP route" method=GET path=/files211peer1 # [6519694.509565] peer1 data-mesher[219]: time=2026-08-23T10:25:20.562Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name212peer1 # [6519694.509565] peer1 data-mesher[219]: time=2026-08-23T10:25:20.562Z level=INFO msg="starting server"213peer1 # [6519694.511047] peer1 data-mesher[219]: time=2026-08-23T10:25:20.564Z level=INFO msg="HTTP server listening" address=[::1]:7331214peer1 # [6519694.511934] peer1 data-mesher[219]: time=2026-08-23T10:25:20.565Z level=INFO msg="waiting for DHT to populate" delay=10s215peer1 # [6519694.512040] peer1 data-mesher[219]: time=2026-08-23T10:25:20.565Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331216peer1 # [6519694.588427] peer1 systemd-logind[239]: New seat seat0.217peer1 # [6519694.588554] peer1 systemd[1]: Started User Login Management.218peer1 # [6519694.612491] peer1 systemd[1]: Starting linger-users.service...219peer1 # [6519694.625021] peer1 systemd[1]: linger-users.service: Deactivated successfully.220peer1 # [6519694.625100] peer1 systemd[1]: Finished linger-users.service.221controller # [6519694.467020] controller data-mesher[230]: time=2026-08-23T10:25:20.520Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]222controller # [6519694.468127] controller data-mesher[230]: time=2026-08-23T10:25:20.521Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf: [/dns/controller.clan/tcp/7946]} {12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC: [/dns/peer1.clan/tcp/7946]} {12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf223controller # [6519694.468127] controller data-mesher[230]: time=2026-08-23T10:25:20.521Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml224controller # [6519694.505446] controller data-mesher[230]: time=2026-08-23T10:25:20.558Z level=INFO msg="checking file integrity"225controller # [6519694.505553] controller data-mesher[230]: time=2026-08-23T10:25:20.558Z level=INFO msg="file integrity check complete"226controller # [6519694.509517] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="libp2p host created" peer_id=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf 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::7a6b:c0bd:35f9:8ca/tcp/7946]"227controller # [6519694.509590] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="registered HTTP route" method=GET path=/files228controller # [6519694.509590] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name229controller # [6519694.509590] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name230controller # [6519694.509590] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="starting server"231controller # [6519694.509755] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="waiting for DHT to populate" delay=10s232controller # [6519694.509755] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="HTTP server listening" address=[::1]:7331233controller # [6519694.509755] controller data-mesher[230]: time=2026-08-23T10:25:20.562Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331234controller # [6519694.579617] controller systemd-logind[250]: New seat seat0.235controller # [6519694.579780] controller systemd[1]: Started User Login Management.236controller # [6519694.581221] controller systemd[1]: Starting linger-users.service...237controller # [6519694.620898] controller systemd[1]: linger-users.service: Deactivated successfully.238controller # [6519694.621022] controller systemd[1]: Finished linger-users.service.239peer1 # [6519695.044142] peer1 systemd-networkd[213]: eth1: Gained IPv6LL240controller # [6519695.936153] controller systemd-networkd[224]: eth1: Gained IPv6LL241controller # [6519699.520311] controller data-mesher[230]: time=2026-08-23T10:25:25.573Z level=INFO msg="peer connected" peer_id=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC remote_addr=/ip4/192.168.1.2/tcp/7946242controller # [6519699.520311] controller data-mesher[230]: time=2026-08-23T10:25:25.573Z level=INFO msg="peer connected" peer_id=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC remote_addr=/ip6/2001:db8:1::2/tcp/7946243controller # [6519699.522169] controller data-mesher[230]: time=2026-08-23T10:25:25.575Z level=INFO msg="peer connected" peer_id=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC remote_addr=/ip6/2001:db8:1::2/tcp/35424244peer1 # [6519699.520298] peer1 data-mesher[219]: time=2026-08-23T10:25:25.573Z level=INFO msg="peer connected" peer_id=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf remote_addr=/ip6/2001:db8:1::1/tcp/7946245peer1 # [6519699.522178] peer1 data-mesher[219]: time=2026-08-23T10:25:25.575Z level=INFO msg="peer connected" peer_id=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf remote_addr=/ip6/2001:db8:1::1/tcp/7946246peer1 # [6519699.522178] peer1 data-mesher[219]: time=2026-08-23T10:25:25.575Z level=INFO msg="peer connected" peer_id=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf remote_addr=/ip4/192.168.1.1/tcp/7946247controller: still waiting for container 'controller' to reach ready state...248controller # [6519704.510361] controller data-mesher[230]: time=2026-08-23T10:25:30.563Z level=INFO msg="performing state exchange with peers on join" count=1249controller # [6519704.510791] controller data-mesher[230]: time=2026-08-23T10:25:30.563Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s250controller # [6519704.511106] controller data-mesher[230]: time=2026-08-23T10:25:30.564Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC251controller # [6519704.511106] controller data-mesher[230]: time=2026-08-23T10:25:30.564Z level=INFO msg="state exchange complete" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s252peer1 # [6519704.510930] peer1 data-mesher[219]: time=2026-08-23T10:25:30.564Z level=INFO msg="received state sync from peer" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf253controller # [6519704.511177] controller data-mesher[230]: time=2026-08-23T10:25:30.564Z level=INFO msg="server started"254peer1 # [6519704.510930] peer1 data-mesher[219]: time=2026-08-23T10:25:30.564Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf255controller # [6519704.511270] controller data-mesher[230]: time=2026-08-23T10:25:30.564Z level=INFO msg="starting expired-file sweeper" interval=1m0s256peer1 # [6519704.512363] peer1 data-mesher[219]: time=2026-08-23T10:25:30.565Z level=INFO msg="performing state exchange with peers on join" count=1257controller # [6519704.511369] controller systemd[1]: Started data mesher daemon.258peer1 # [6519704.512422] peer1 data-mesher[219]: time=2026-08-23T10:25:30.565Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s259controller # [6519704.512720] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...260peer1 # [6519704.512846] peer1 data-mesher[219]: time=2026-08-23T10:25:30.566Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf261controller # [6519704.512794] controller data-mesher[230]: time=2026-08-23T10:25:30.565Z level=INFO msg="received state sync from peer" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC262peer1 # [6519704.512846] peer1 data-mesher[219]: time=2026-08-23T10:25:30.566Z level=INFO msg="state exchange complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s263controller # [6519704.512794] controller data-mesher[230]: time=2026-08-23T10:25:30.565Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC264peer1 # [6519704.512901] peer1 data-mesher[219]: time=2026-08-23T10:25:30.566Z level=INFO msg="server started"265controller # [6519704.580896] controller data-mesher[230]: time=2026-08-23T10:25:30.634Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204266peer1 # [6519704.512997] peer1 data-mesher[219]: time=2026-08-23T10:25:30.566Z level=INFO msg="starting expired-file sweeper" interval=1m0s267controller # [6519704.581140] controller dm-wg-star-publish[295]: Status: 204 No Content268peer1 # [6519704.513078] peer1 systemd[1]: Started data mesher daemon.269controller # [6519704.582587] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...270peer1 # [6519704.514348] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...271peer1 # [6519704.665264] peer1 data-mesher[219]: time=2026-08-23T10:25:30.718Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 status=204272peer1 # [6519704.665535] peer1 dm-wg-star-publish[287]: Status: 204 No Content273peer1 # [6519704.667038] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...274peer1 # [6519704.668831] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.275peer1 # [6519704.669012] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.276controller # [6519704.584392] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.277peer1 # [6519704.669561] peer1 systemd[1]: Reached target Multi-User System.278controller # [6519704.584834] controller systemd[1]: Reached target Multi-User System.279peer1 # [6519704.749258] peer1 dm-wg-star-reconfig[315]: No controller data available yet, skipping280controller # [6519704.673216] controller dm-wg-star-reconfig[323]: No peer data available yet, skipping281peer1 # [6519704.750191] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.282controller # [6519704.674247] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.283peer1 # [6519704.750284] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.284controller # [6519704.674418] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.285peer1 # [6519704.750639] peer1 systemd[1]: Startup finished in 11.859s.286controller # [6519704.674826] controller systemd[1]: Startup finished in 11.767s.287controller: (finished: waiting for unit data-mesher.service, in 12.67 seconds)288peer1: waiting for unit data-mesher.service289peer1: (finished: waiting for unit data-mesher.service, in 0.02 seconds)290??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.291 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39292controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller293??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.294 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39295controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.01 seconds)296peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller297controller # [6519709.512170] controller data-mesher[230]: time=2026-08-23T10:25:35.565Z level=DEBUG msg="attempting push/pull" peer_count=1298controller # [6519709.512857] controller data-mesher[230]: time=2026-08-23T10:25:35.565Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s299controller # [6519709.513159] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC300controller # [6519709.513295] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=INFO msg="received state sync from peer" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC301controller # [6519709.513295] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC302controller # [6519709.513450] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=INFO msg="received file request" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/controller303controller # [6519709.513514] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=DEBUG msg="new file detected" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38304controller # [6519709.513588] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38305controller # [6519709.513650] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 signed_at="2026-08-23 10:25:30.714 +0000 UTC" signed_by="YdOCQ+gAoW5mWcVTVyY4+MAKAJ+uC6V0KfMyq8uFN38=" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC306controller # [6519709.513787] controller data-mesher[230]: time=2026-08-23T10:25:35.566Z level=DEBUG msg="new file detected" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38307controller # [6519709.514256] controller data-mesher[230]: time=2026-08-23T10:25:35.567Z level=INFO msg="state exchange complete" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s308controller # [6519709.514320] controller data-mesher[230]: time=2026-08-23T10:25:35.567Z level=DEBUG msg="push/pull successful" interval=5s309controller # [6519709.514374] controller data-mesher[230]: time=2026-08-23T10:25:35.567Z level=INFO msg="file transfer complete" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/controller310controller # [6519709.522689] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...311controller # [6519709.545978] controller data-mesher[230]: time=2026-08-23T10:25:35.599Z level=INFO msg="download complete" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 signed_at="2026-08-23 10:25:30.714 +0000 UTC" signed_by="YdOCQ+gAoW5mWcVTVyY4+MAKAJ+uC6V0KfMyq8uFN38=" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC written=true elapsed=32.339724ms312controller # [6519709.633235] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.313controller # [6519709.633312] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.314peer1 # [6519709.512717] peer1 data-mesher[219]: time=2026-08-23T10:25:35.565Z level=INFO msg="received state sync from peer" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf315peer1 # [6519709.512717] peer1 data-mesher[219]: time=2026-08-23T10:25:35.565Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf316peer1 # [6519709.513113] peer1 data-mesher[219]: time=2026-08-23T10:25:35.565Z level=DEBUG msg="new file detected" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller317peer1 # [6519709.513113] peer1 data-mesher[219]: time=2026-08-23T10:25:35.566Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller318peer1 # [6519709.513113] peer1 data-mesher[219]: time=2026-08-23T10:25:35.566Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-08-23 10:25:30.628 +0000 UTC" signed_by="Dt8bqhDEL81s3lPCfK3uoEW6rcAkC1Xjow8Kbx96crA=" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf319peer1 # [6519709.513113] peer1 data-mesher[219]: time=2026-08-23T10:25:35.566Z level=DEBUG msg="attempting push/pull" peer_count=1320peer1 # [6519709.513113] peer1 data-mesher[219]: time=2026-08-23T10:25:35.566Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s321peer1 # [6519709.513906] peer1 data-mesher[219]: time=2026-08-23T10:25:35.567Z level=INFO msg="received file request" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38322peer1 # [6519709.513945] peer1 data-mesher[219]: time=2026-08-23T10:25:35.567Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf323peer1 # [6519709.513969] peer1 data-mesher[219]: time=2026-08-23T10:25:35.567Z level=DEBUG msg="new file detected" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller324peer1 # [6519709.513969] peer1 data-mesher[219]: time=2026-08-23T10:25:35.567Z level=INFO msg="state exchange complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s325peer1 # [6519709.514013] peer1 data-mesher[219]: time=2026-08-23T10:25:35.567Z level=DEBUG msg="push/pull successful" interval=5s326peer1 # [6519709.516193] peer1 data-mesher[219]: time=2026-08-23T10:25:35.569Z level=INFO msg="file transfer complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38327peer1 # [6519709.519152] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...328peer1 # [6519709.545776] peer1 data-mesher[219]: time=2026-08-23T10:25:35.598Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-08-23 10:25:30.628 +0000 UTC" signed_by="Dt8bqhDEL81s3lPCfK3uoEW6rcAkC1Xjow8Kbx96crA=" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf written=true elapsed=32.86345ms329peer1 # [6519709.629831] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.330peer1 # [6519709.629999] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.331peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.04 seconds)332controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q .333controller: (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)334controller: waiting for success: wg show wg-star peers | grep -q .335controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)336peer1: waiting for success: wg show wg-star peers | grep -q .337peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)338peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::7a6b:c0bd:35f9:08ca339controller # [6519714.515158] controller data-mesher[230]: time=2026-08-23T10:25:40.568Z level=INFO msg="received state sync from peer" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC340controller # [6519714.515158] controller data-mesher[230]: time=2026-08-23T10:25:40.568Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC341controller # [6519714.515859] controller data-mesher[230]: time=2026-08-23T10:25:40.568Z level=DEBUG msg="attempting push/pull" peer_count=1342controller # [6519714.515859] controller data-mesher[230]: time=2026-08-23T10:25:40.568Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s343peer1 # [6519714.514515] peer1 data-mesher[219]: time=2026-08-23T10:25:40.567Z level=DEBUG msg="attempting push/pull" peer_count=1344peer1 # [6519714.515211] peer1 data-mesher[219]: time=2026-08-23T10:25:40.567Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s345peer1 # [6519714.515655] peer1 data-mesher[219]: time=2026-08-23T10:25:40.568Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf346peer1 # [6519714.515926] peer1 data-mesher[219]: time=2026-08-23T10:25:40.569Z level=INFO msg="state exchange complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s347peer1 # [6519714.515993] peer1 data-mesher[219]: time=2026-08-23T10:25:40.569Z level=DEBUG msg="push/pull successful" interval=5s348controller # [6519714.516537] controller data-mesher[230]: time=2026-08-23T10:25:40.569Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC349controller # [6519714.516833] controller data-mesher[230]: time=2026-08-23T10:25:40.569Z level=INFO msg="state exchange complete" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s350peer1 # [6519714.516068] peer1 data-mesher[219]: time=2026-08-23T10:25:40.569Z level=INFO msg="received state sync from peer" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf351controller # [6519714.516895] controller data-mesher[230]: time=2026-08-23T10:25:40.570Z level=DEBUG msg="push/pull successful" interval=5s352peer1 # [6519714.516126] peer1 data-mesher[219]: time=2026-08-23T10:25:40.569Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf353peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::7a6b:c0bd:35f9:08ca, in 4.93 seconds)354controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eac1:0e45:4302:cfcf355controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eac1:0e45:4302:cfcf, in 0.01 seconds)356controller: must succeed: wg show wg-star peers | wc -l357controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.01 seconds)358peer2: systemd-nspawn running (pid 783)359peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...360peer2: waiting for unit data-mesher.service361nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE362nixos-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.363Note: 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.364░ Spawning container peer2 on /build/vm-state-peer2.365peer2 # [6519716.033672] peer2 systemd-journald[96]: Journal started366peer2 # [6519716.033730] peer2 systemd-journald[96]: Runtime Journal (/run/log/journal/9b4fa4a5e1d846058434f29c92334aab) is 8M, max 2.5G, 2.4G free.367peer2 # [6519716.042147] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...368peer2 # [6519716.043351] peer2 systemd[1]: Starting Network Name Resolution...369peer2 # [6519716.043947] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...370peer2 # [6519716.052877] peer2 systemd-journald[96]: Time spent on flushing to /var/log/journal/9b4fa4a5e1d846058434f29c92334aab is 1.394ms for 5 entries.371peer2 # [6519716.052877] peer2 systemd-journald[96]: System Journal (/var/log/journal/9b4fa4a5e1d846058434f29c92334aab) is 8M, max 4G, 3.9G free.372peer2 # [6519716.058588] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.373peer2 # [6519716.058785] peer2 systemd[1]: Reached target Preparation for Local File Systems.374peer2 # [6519716.058861] peer2 systemd[1]: Reached target Local File Systems.375peer2 # [6519716.059563] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.376peer2 # [6519716.059600] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container377peer2 # [6519716.060375] peer2 systemd[1]: Starting Save Transient machine-id to Disk...378peer2 # [6519716.060405] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys379peer2 # [6519716.066451] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.380peer2 # [6519716.067330] peer2 systemd[1]: Starting Create System Files and Directories...381peer2 # [6519716.085564] peer2 systemd-tmpfiles[139]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted382peer2 # [6519716.085775] peer2 systemd-tmpfiles[139]: fchmod() of /var/log/journal failed: Operation not permitted383peer2 # [6519716.085918] peer2 systemd-tmpfiles[139]: fchmod() of /var/log/journal/9b4fa4a5e1d846058434f29c92334aab failed: Operation not permitted384peer2 # [6519716.086127] peer2 systemd-tmpfiles[139]: fchmod() of /run/log/journal failed: Operation not permitted385peer2 # [6519716.087542] peer2 systemd[1]: Finished Create System Files and Directories.386peer2 # [6519716.088572] peer2 systemd[1]: Starting Rebuild Journal Catalog...387peer2 # [6519716.089249] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...388peer2 # [6519716.102925] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.389peer2 # [6519716.108652] peer2 systemd[1]: Finished Rebuild Journal Catalog.390peer2 # [6519716.109665] peer2 systemd[1]: Starting Update is Completed...391peer2 # [6519716.120892] peer2 systemd[1]: Finished Update is Completed.392peer2 # [6519716.183327] peer2 systemd[1]: Finished Firewall.393peer2 # [6519716.183488] peer2 systemd[1]: Reached target Preparation for Network.394peer2 # [6519716.183711] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.395peer2 # [6519716.184756] peer2 systemd[1]: Starting Network Management...396peer2 # [6519716.598994] peer2 systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted397peer2 # [6519716.599090] peer2 systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted398peer2 # [6519716.606213] 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.399peer2 # [6519716.606375] 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.400peer2 # [6519716.606543] peer2 systemd-networkd[213]: lo: Link UP401peer2 # [6519716.606547] peer2 systemd-networkd[213]: lo: Gained carrier402peer2 # [6519716.606736] peer2 systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.403peer2 # [6519716.607318] peer2 systemd[1]: Started Network Management.404peer2 # [6519716.608260] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...405peer2 # [6519716.608408] peer2 systemd-networkd[213]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.406peer2 # [6519716.608834] peer2 systemd-networkd[213]: wg-star: netdev ready407peer2 # [6519716.609203] peer2 systemd-networkd[213]: eth1: Link UP408peer2 # [6519716.609387] peer2 systemd-networkd[213]: eth1: Gained carrier409peer2 # [6519716.669086] peer2 systemd-networkd[213]: wg-star: Link UP410peer2 # [6519716.669092] peer2 systemd-networkd[213]: wg-star: Gained carrier411peer2 # [6519716.670788] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.412peer2 # [6519716.675259] peer2 systemd-resolved[119]: Positive Trust Anchors:413peer2 # [6519716.675268] peer2 systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d414peer2 # [6519716.675272] peer2 systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16415peer2 # [6519716.675306] peer2 systemd-resolved[119]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test416peer2 # [6519716.697381] peer2 systemd-resolved[119]: Using system hostname 'peer2'.417peer2 # [6519716.698921] peer2 systemd[1]: Started Network Name Resolution.418peer2 # [6519716.699015] peer2 systemd[1]: Reached target Network.419peer2 # [6519716.699071] peer2 systemd[1]: Reached target System Initialization.420peer2 # [6519716.699144] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.421peer2 # [6519716.699172] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.422peer2 # [6519716.699197] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container423peer2 # [6519716.699215] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.424peer2 # [6519716.699233] peer2 systemd[1]: Reached target Path Units.425peer2 # [6519716.699260] peer2 systemd[1]: Reached target Timer Units.426peer2 # [6519716.699367] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.427peer2 # [6519716.699484] peer2 systemd[1]: Listening on Nix Daemon Socket.428peer2 # [6519716.699581] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.429peer2 # [6519716.699602] peer2 systemd[1]: Reached target Socket Units.430peer2 # [6519716.699638] peer2 systemd[1]: Reached target Basic System.431peer2 # [6519716.700758] peer2 systemd[1]: Starting data mesher daemon...432peer2 # [6519716.701382] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...433peer2 # [6519716.702027] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...434peer2 # [6519716.703108] peer2 systemd[1]: Starting D-Bus System Message Bus...435peer2 # [6519716.718543] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.436peer2 # [6519716.807702] peer2 nsncd[220]: Aug 23 10:25:42.860 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"437peer2 # [6519716.807796] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).438peer2 # [6519716.807857] peer2 systemd[1]: Reached target Host and Network Name Lookups.439peer2 # [6519716.807917] peer2 systemd[1]: Reached target User and Group Name Lookups.440peer2 # [6519716.809064] peer2 systemd[1]: Starting User Login Management...441peer2 # [6519716.809843] peer2 systemd[1]: Starting Permit User Sessions...442peer2 # [6519716.855137] peer2 systemd[1]: Finished Permit User Sessions.443peer2 # [6519716.856159] peer2 systemd[1]: Started Console Getty.444peer2 # [6519716.856199] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0445peer2 # [6519716.856214] peer2 systemd[1]: Reached target Login Prompts.446peer2 # [6519716.895179] peer2 dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...447peer2 # [6519716.896011] peer2 dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'448peer2 # [6519716.896011] 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"449peer2 # [6519716.896587] peer2 systemd[1]: Started D-Bus System Message Bus.450peer2 # [6519716.903390] peer2 dbus-broker-launch[221]: Ready451peer2 # [6519716.960317] peer2 systemd[1]: Finished Save Transient machine-id to Disk.452peer2 # [6519717.017400] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.453peer2 # [6519717.131699] peer2 data-mesher[218]: time=2026-08-23T10:25:43.184Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]454peer2 # [6519717.132995] peer2 data-mesher[218]: time=2026-08-23T10:25:43.186Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf: [/dns/controller.clan/tcp/7946]} {12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC: [/dns/peer1.clan/tcp/7946]} {12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh455peer2 # [6519717.132995] peer2 data-mesher[218]: time=2026-08-23T10:25:43.186Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml456peer2 # [6519717.135455] peer2 data-mesher[218]: time=2026-08-23T10:25:43.188Z level=INFO msg="checking file integrity"457peer2 # [6519717.135569] peer2 data-mesher[218]: time=2026-08-23T10:25:43.188Z level=INFO msg="file integrity check complete"458peer2 # [6519717.139874] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="libp2p host created" peer_id=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh 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::b428:96de:19d2:5ae7/tcp/7946]"459peer2 # [6519717.139950] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="registered HTTP route" method=GET path=/files460peer2 # [6519717.139950] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name461peer2 # [6519717.139950] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name462peer2 # [6519717.139950] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="starting server"463peer2 # [6519717.140146] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="waiting for DHT to populate" delay=10s464peer2 # [6519717.140293] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="HTTP server listening" address=[::1]:7331465peer2 # [6519717.140361] peer2 data-mesher[218]: time=2026-08-23T10:25:43.193Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331466peer2 # [6519717.145271] peer2 data-mesher[218]: time=2026-08-23T10:25:43.198Z level=INFO msg="peer connected" peer_id=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC remote_addr=/ip4/192.168.1.2/tcp/7946467peer2 # [6519717.152536] peer2 data-mesher[218]: time=2026-08-23T10:25:43.205Z level=INFO msg="peer connected" peer_id=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf remote_addr=/ip4/192.168.1.1/tcp/7946468peer2 # [6519717.264783] peer2 systemd-logind[238]: New seat seat0.469peer2 # [6519717.264992] peer2 systemd[1]: Started User Login Management.470peer2 # [6519717.267248] peer2 systemd[1]: Starting linger-users.service...471peer2 # [6519717.322879] peer2 systemd[1]: linger-users.service: Deactivated successfully.472peer2 # [6519717.323194] peer2 systemd[1]: Finished linger-users.service.473controller # [6519717.153514] controller data-mesher[230]: time=2026-08-23T10:25:43.206Z level=INFO msg="peer connected" peer_id=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh remote_addr=/ip4/192.168.1.3/tcp/7946474peer1 # [6519717.148029] peer1 data-mesher[219]: time=2026-08-23T10:25:43.199Z level=INFO msg="peer connected" peer_id=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh remote_addr=/ip4/192.168.1.3/tcp/7946475peer2 # [6519717.984173] peer2 systemd-networkd[213]: eth1: Gained IPv6LL476peer2 # [6519719.517510] peer2 data-mesher[218]: time=2026-08-23T10:25:45.570Z level=INFO msg="received state sync from peer" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC477peer2 # [6519719.517510] peer2 data-mesher[218]: time=2026-08-23T10:25:45.570Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC478peer2 # [6519719.517858] peer2 data-mesher[218]: time=2026-08-23T10:25:45.570Z level=DEBUG msg="new file detected" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38479peer2 # [6519719.517858] peer2 data-mesher[218]: time=2026-08-23T10:25:45.570Z level=DEBUG msg="new file detected" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller480peer2 # [6519719.517858] peer2 data-mesher[218]: time=2026-08-23T10:25:45.570Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38481peer2 # [6519719.517858] peer2 data-mesher[218]: time=2026-08-23T10:25:45.570Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller482peer2 # [6519719.517858] peer2 data-mesher[218]: time=2026-08-23T10:25:45.570Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-08-23 10:25:30.628 +0000 UTC" signed_by="Dt8bqhDEL81s3lPCfK3uoEW6rcAkC1Xjow8Kbx96crA=" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC483peer2 # [6519719.517955] peer2 data-mesher[218]: time=2026-08-23T10:25:45.571Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 signed_at="2026-08-23 10:25:30.714 +0000 UTC" signed_by="YdOCQ+gAoW5mWcVTVyY4+MAKAJ+uC6V0KfMyq8uFN38=" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC484peer2 # [6519719.522250] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...485peer2 # [6519719.524446] peer2 data-mesher[218]: time=2026-08-23T10:25:45.577Z level=INFO msg="download complete" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38 signed_at="2026-08-23 10:25:30.714 +0000 UTC" signed_by="YdOCQ+gAoW5mWcVTVyY4+MAKAJ+uC6V0KfMyq8uFN38=" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC written=true elapsed=6.244565ms486peer2 # [6519719.527260] peer2 data-mesher[218]: time=2026-08-23T10:25:45.578Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-08-23 10:25:30.628 +0000 UTC" signed_by="Dt8bqhDEL81s3lPCfK3uoEW6rcAkC1Xjow8Kbx96crA=" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC written=true elapsed=7.104017ms487peer2 # [6519719.673273] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.488peer2 # [6519719.712391] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.489controller # [6519719.517894] controller data-mesher[230]: time=2026-08-23T10:25:45.571Z level=DEBUG msg="attempting push/pull" peer_count=1490controller # [6519719.518219] controller data-mesher[230]: time=2026-08-23T10:25:45.571Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s491controller # [6519719.522047] controller data-mesher[230]: time=2026-08-23T10:25:45.574Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC492controller # [6519719.522047] controller data-mesher[230]: time=2026-08-23T10:25:45.575Z level=INFO msg="state exchange complete" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s493controller # [6519719.522047] controller data-mesher[230]: time=2026-08-23T10:25:45.575Z level=DEBUG msg="push/pull successful" interval=5s494peer1 # [6519719.516728] peer1 data-mesher[219]: time=2026-08-23T10:25:45.569Z level=DEBUG msg="attempting push/pull" peer_count=1495peer1 # [6519719.516728] peer1 data-mesher[219]: time=2026-08-23T10:25:45.569Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s496peer1 # [6519719.518214] peer1 data-mesher[219]: time=2026-08-23T10:25:45.571Z level=INFO msg="received file request" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38497peer1 # [6519719.518404] peer1 data-mesher[219]: time=2026-08-23T10:25:45.571Z level=INFO msg="file transfer complete" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/YdOCQ-gAoW5mWcVTVyY4-MAKAJ-uC6V0KfMyq8uFN38498peer1 # [6519719.518733] peer1 data-mesher[219]: time=2026-08-23T10:25:45.571Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh499peer1 # [6519719.518733] peer1 data-mesher[219]: time=2026-08-23T10:25:45.571Z level=INFO msg="state exchange complete" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s500peer1 # [6519719.518806] peer1 data-mesher[219]: time=2026-08-23T10:25:45.571Z level=DEBUG msg="push/pull successful" interval=5s501peer1 # [6519719.518922] peer1 data-mesher[219]: time=2026-08-23T10:25:45.572Z level=INFO msg="received file request" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/controller502peer1 # [6519719.522217] peer1 data-mesher[219]: time=2026-08-23T10:25:45.574Z level=INFO msg="received state sync from peer" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf503peer1 # [6519719.522217] peer1 data-mesher[219]: time=2026-08-23T10:25:45.574Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf504peer1 # [6519719.522217] peer1 data-mesher[219]: time=2026-08-23T10:25:45.574Z level=INFO msg="file transfer complete" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/controller505peer2 # [6519724.523744] peer2 data-mesher[218]: time=2026-08-23T10:25:50.576Z level=INFO msg="received state sync from peer" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf506peer2 # [6519724.523744] peer2 data-mesher[218]: time=2026-08-23T10:25:50.576Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf507peer1 # [6519724.519157] peer1 data-mesher[219]: time=2026-08-23T10:25:50.572Z level=DEBUG msg="attempting push/pull" peer_count=1508controller # [6519724.519675] controller data-mesher[230]: time=2026-08-23T10:25:50.572Z level=INFO msg="received state sync from peer" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC509peer1 # [6519724.519157] peer1 data-mesher[219]: time=2026-08-23T10:25:50.572Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s510controller # [6519724.519675] controller data-mesher[230]: time=2026-08-23T10:25:50.572Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC511peer1 # [6519724.520182] peer1 data-mesher[219]: time=2026-08-23T10:25:50.573Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf512controller # [6519724.523067] controller data-mesher[230]: time=2026-08-23T10:25:50.576Z level=DEBUG msg="attempting push/pull" peer_count=1513peer1 # [6519724.520706] peer1 data-mesher[219]: time=2026-08-23T10:25:50.573Z level=INFO msg="state exchange complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s514controller # [6519724.523172] controller data-mesher[230]: time=2026-08-23T10:25:50.576Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s515peer1 # [6519724.520706] peer1 data-mesher[219]: time=2026-08-23T10:25:50.573Z level=DEBUG msg="push/pull successful" interval=5s516controller # [6519724.524517] controller data-mesher[230]: time=2026-08-23T10:25:50.577Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh517controller # [6519724.526635] controller data-mesher[230]: time=2026-08-23T10:25:50.577Z level=INFO msg="state exchange complete" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s518controller # [6519724.526635] controller data-mesher[230]: time=2026-08-23T10:25:50.577Z level=DEBUG msg="push/pull successful" interval=5s519peer2: still waiting for container 'peer2' to reach ready state...520peer2 # [6519727.140465] peer2 data-mesher[218]: time=2026-08-23T10:25:53.193Z level=INFO msg="performing state exchange with peers on join" count=1521peer2 # [6519727.140840] peer2 data-mesher[218]: time=2026-08-23T10:25:53.193Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s522peer2 # [6519727.141436] peer2 data-mesher[218]: time=2026-08-23T10:25:53.194Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf523peer2 # [6519727.141653] peer2 data-mesher[218]: time=2026-08-23T10:25:53.194Z level=INFO msg="state exchange complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s524peer2 # [6519727.141701] peer2 data-mesher[218]: time=2026-08-23T10:25:53.194Z level=INFO msg="server started"525peer2 # [6519727.141803] peer2 data-mesher[218]: time=2026-08-23T10:25:53.194Z level=INFO msg="starting expired-file sweeper" interval=1m0s526peer2 # [6519727.141887] peer2 systemd[1]: Started data mesher daemon.527peer2 # [6519727.143037] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...528peer2 # [6519727.253992] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...529peer2 # [6519727.255593] peer2 data-mesher[218]: time=2026-08-23T10:25:53.308Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY status=204530peer2 # [6519727.255734] peer2 dm-wg-star-publish[295]: Status: 204 No Content531peer2 # [6519727.258453] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.532peer2 # [6519727.296246] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.533peer2 # [6519727.296897] peer2 systemd[1]: Reached target Multi-User System.534controller # [6519727.140997] controller data-mesher[230]: time=2026-08-23T10:25:53.194Z level=INFO msg="received state sync from peer" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh535controller # [6519727.140997] controller data-mesher[230]: time=2026-08-23T10:25:53.194Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh536peer2 # [6519727.395191] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.537peer2 # [6519727.395371] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.538peer2 # [6519727.395643] peer2 systemd[1]: Startup finished in 11.816s.539peer2: (finished: waiting for unit data-mesher.service, in 12.66 seconds)540??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.541 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39542controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1543??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.544 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39545peer2 # [6519729.521577] peer2 data-mesher[218]: time=2026-08-23T10:25:55.574Z level=INFO msg="received state sync from peer" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC546peer2 # [6519729.521577] peer2 data-mesher[218]: time=2026-08-23T10:25:55.574Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC547peer2 # [6519729.524427] peer2 data-mesher[218]: time=2026-08-23T10:25:55.577Z level=INFO msg="received file request" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY548peer2 # [6519729.529112] peer2 data-mesher[218]: time=2026-08-23T10:25:55.582Z level=INFO msg="file transfer complete" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY549controller # [6519729.525673] controller data-mesher[230]: time=2026-08-23T10:25:55.578Z level=DEBUG msg="attempting push/pull" peer_count=1550controller # [6519729.525673] controller data-mesher[230]: time=2026-08-23T10:25:55.578Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s551controller # [6519729.526609] controller data-mesher[230]: time=2026-08-23T10:25:55.579Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC552controller # [6519729.526936] controller data-mesher[230]: time=2026-08-23T10:25:55.580Z level=INFO msg="state exchange complete" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC timeout=5s553controller # [6519729.526974] controller data-mesher[230]: time=2026-08-23T10:25:55.580Z level=DEBUG msg="push/pull successful" interval=5s554peer1 # [6519729.520825] peer1 data-mesher[219]: time=2026-08-23T10:25:55.573Z level=DEBUG msg="attempting push/pull" peer_count=1555peer1 # [6519729.520825] peer1 data-mesher[219]: time=2026-08-23T10:25:55.574Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s556peer1 # [6519729.524909] peer1 data-mesher[219]: time=2026-08-23T10:25:55.575Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh557peer1 # [6519729.524909] peer1 data-mesher[219]: time=2026-08-23T10:25:55.576Z level=DEBUG msg="new file detected" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY558peer1 # [6519729.524909] peer1 data-mesher[219]: time=2026-08-23T10:25:55.576Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY559peer1 # [6519729.524909] peer1 data-mesher[219]: time=2026-08-23T10:25:55.576Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY signed_at="2026-08-23 10:25:53.303 +0000 UTC" signed_by="C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY=" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh560peer1 # [6519729.524909] peer1 data-mesher[219]: time=2026-08-23T10:25:55.576Z level=INFO msg="state exchange complete" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s561peer1 # [6519729.524909] peer1 data-mesher[219]: time=2026-08-23T10:25:55.576Z level=DEBUG msg="push/pull successful" interval=5s562peer1 # [6519729.526111] peer1 data-mesher[219]: time=2026-08-23T10:25:55.579Z level=INFO msg="received state sync from peer" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf563peer1 # [6519729.526111] peer1 data-mesher[219]: time=2026-08-23T10:25:55.579Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf564peer1 # [6519729.533026] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...565peer1 # [6519729.536789] peer1 data-mesher[219]: time=2026-08-23T10:25:55.589Z level=INFO msg="download complete" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY signed_at="2026-08-23 10:25:53.303 +0000 UTC" signed_by="C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY=" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh written=true elapsed=13.429184ms566peer1 # [6519729.700708] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.567peer1 # [6519729.701026] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.568peer2 # [6519732.142757] peer2 data-mesher[218]: time=2026-08-23T10:25:58.195Z level=DEBUG msg="attempting push/pull" peer_count=2569peer2 # [6519732.143448] peer2 data-mesher[218]: time=2026-08-23T10:25:58.195Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s570peer2 # [6519732.144412] peer2 data-mesher[218]: time=2026-08-23T10:25:58.197Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf571peer2 # [6519732.144537] peer2 data-mesher[218]: time=2026-08-23T10:25:58.197Z level=INFO msg="received file request" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY572peer2 # [6519732.144730] peer2 data-mesher[218]: time=2026-08-23T10:25:58.197Z level=INFO msg="state exchange complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s573peer2 # [6519732.144793] peer2 data-mesher[218]: time=2026-08-23T10:25:58.197Z level=DEBUG msg="push/pull successful" interval=5s574peer2 # [6519732.146607] peer2 data-mesher[218]: time=2026-08-23T10:25:58.199Z level=INFO msg="file transfer complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf network="ynivK/VxgUNYwnzkAmzwXB5c+7PyhlB7cEVFQTY9U/8=" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY575controller # [6519732.143343] controller data-mesher[230]: time=2026-08-23T10:25:58.196Z level=INFO msg="received state sync from peer" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh576controller # [6519732.143343] controller data-mesher[230]: time=2026-08-23T10:25:58.196Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh577controller # [6519732.144085] controller data-mesher[230]: time=2026-08-23T10:25:58.196Z level=DEBUG msg="new file detected" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY578controller # [6519732.144085] controller data-mesher[230]: time=2026-08-23T10:25:58.196Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY579controller # [6519732.144085] controller data-mesher[230]: time=2026-08-23T10:25:58.196Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY signed_at="2026-08-23 10:25:53.303 +0000 UTC" signed_by="C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY=" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh580controller # [6519732.151974] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...581controller # [6519732.191789] controller data-mesher[230]: time=2026-08-23T10:25:58.243Z level=INFO msg="download complete" name=dm_wg_star_wg_star/C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY signed_at="2026-08-23 10:25:53.303 +0000 UTC" signed_by="C2uwQpssKPfbAgoXCV7Y0Dr5Y5uhbdbt1JCLI6WNrxY=" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh written=true elapsed=46.853602ms582controller # [6519732.307432] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.583controller # [6519732.307655] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.584controller: (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 5.08 seconds)585controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1586controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.01 seconds)587peer2: waiting for success: wg show wg-star peers | grep -q .588peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)589peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::7a6b:c0bd:35f9:08ca590peer2 # [6519734.525207] peer2 data-mesher[218]: time=2026-08-23T10:26:00.578Z level=INFO msg="received state sync from peer" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC591peer2 # [6519734.525207] peer2 data-mesher[218]: time=2026-08-23T10:26:00.578Z level=INFO msg="merging remote state" peer=12D3KooWGQEmuKjYmfBVndyrKziH22CFK2cNWdwpu8kVasVXkaDC592peer2 # [6519734.528602] peer2 data-mesher[218]: time=2026-08-23T10:26:00.581Z level=INFO msg="received state sync from peer" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf593peer1 # [6519734.524647] peer1 data-mesher[219]: time=2026-08-23T10:26:00.577Z level=DEBUG msg="attempting push/pull" peer_count=1594peer2 # [6519734.528602] peer2 data-mesher[218]: time=2026-08-23T10:26:00.581Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf595controller # [6519734.527942] controller data-mesher[230]: time=2026-08-23T10:26:00.581Z level=DEBUG msg="attempting push/pull" peer_count=1596peer1 # [6519734.524647] peer1 data-mesher[219]: time=2026-08-23T10:26:00.577Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s597controller # [6519734.527942] controller data-mesher[230]: time=2026-08-23T10:26:00.581Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s598peer1 # [6519734.525890] peer1 data-mesher[219]: time=2026-08-23T10:26:00.579Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh599controller # [6519734.530070] controller data-mesher[230]: time=2026-08-23T10:26:00.583Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh600peer1 # [6519734.526314] peer1 data-mesher[219]: time=2026-08-23T10:26:00.579Z level=INFO msg="state exchange complete" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s601controller # [6519734.530669] controller data-mesher[230]: time=2026-08-23T10:26:00.583Z level=INFO msg="state exchange complete" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh timeout=5s602peer1 # [6519734.526390] peer1 data-mesher[219]: time=2026-08-23T10:26:00.579Z level=DEBUG msg="push/pull successful" interval=5s603controller # [6519734.530715] controller data-mesher[230]: time=2026-08-23T10:26:00.583Z level=DEBUG msg="push/pull successful" interval=5s604peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::7a6b:c0bd:35f9:08ca, in 2.44 seconds)605controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::b428:96de:19d2:5ae7606controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::b428:96de:19d2:5ae7, in 0.01 seconds)607peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::b428:96de:19d2:5ae7608peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::b428:96de:19d2:5ae7, in 0.01 seconds)609peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eac1:0e45:4302:cfcf610peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eac1:0e45:4302:cfcf, in 0.01 seconds)611(finished: run the VM test script, in 42.96 seconds)612test script finished in 45.01s613cleanup614kill NspawnMachine (pid 50)615peer2 # [6519737.144900] peer2 data-mesher[218]: time=2026-08-23T10:26:03.198Z level=DEBUG msg="attempting push/pull" peer_count=2616peer2 # [6519737.145613] peer2 data-mesher[218]: time=2026-08-23T10:26:03.198Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s617peer2 # [6519737.147358] peer2 data-mesher[218]: time=2026-08-23T10:26:03.200Z level=INFO msg="merging remote state" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf618peer2 # [6519737.148557] peer2 data-mesher[218]: time=2026-08-23T10:26:03.201Z level=INFO msg="state exchange complete" peer=12D3KooWBxpGgzrxnhAvdi1nPGPMteoQnLGj2sMaAaYreJPCqaqf timeout=5s619peer2 # [6519737.148641] peer2 data-mesher[218]: time=2026-08-23T10:26:03.201Z level=DEBUG msg="push/pull successful" interval=5s620controller # [6519737.145734] controller data-mesher[230]: time=2026-08-23T10:26:03.198Z level=INFO msg="received state sync from peer" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh621controller # [6519737.145734] controller data-mesher[230]: time=2026-08-23T10:26:03.198Z level=INFO msg="merging remote state" peer=12D3KooWAawtnr5N9QX7o2j1hKoKxuSgWsf1iaYXKpYDfmqQgxSh622controller # [6519737.355599] controller systemd-networkd[224]: eth1: Link DOWN623controller # [6519737.355626] controller systemd-networkd[224]: eth1: Lost carrier624kill NspawnMachine (pid 53)625Container controller terminated by signal KILL.626kill NspawnMachine (pid 783)627Container peer1 terminated by signal KILL.628Container peer2 terminated by signal KILL.629(finished: cleanup, in 0.79 seconds)