container-test-run-dm-wireguard-star
checks.aarch64-linux.dm-wireguard-star
· build #103
· 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.21░ Spawning container controller on /build/vm-state-controller.22Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.23░ Spawning container peer1 on /build/vm-state-peer1.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 # [7504940.364157] controller systemd-journald[105]: Journal started29controller # [7504940.364235] controller systemd-journald[105]: Runtime Journal (/run/log/journal/e75dc48847bc44bea34335b9fdd3fed7) is 8M, max 2.5G, 2.4G free.30controller # [7504940.369920] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.31controller # [7504940.380171] controller systemd[1]: Starting Flush Journal to Persistent Storage...32controller # [7504940.381146] controller systemd[1]: Starting Network Name Resolution...33controller # [7504940.381845] controller systemd[1]: Starting Create Static Device Nodes in /dev...34controller # [7504940.390805] controller systemd-journald[105]: Time spent on flushing to /var/log/journal/e75dc48847bc44bea34335b9fdd3fed7 is 1.417ms for 6 entries.35controller # [7504940.390805] controller systemd-journald[105]: System Journal (/var/log/journal/e75dc48847bc44bea34335b9fdd3fed7) is 8M, max 4G, 3.9G free.36controller # [7504940.396902] controller systemd[1]: Finished Create Static Device Nodes in /dev.37controller # [7504940.397133] controller systemd[1]: Reached target Preparation for Local File Systems.38controller # [7504940.397222] controller systemd[1]: Reached target Local File Systems.39controller # [7504940.397951] controller systemd[1]: Listening on Boot Loader Control Service Socket.40controller # [7504940.397995] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container41controller # [7504940.398902] controller systemd[1]: Starting Save Transient machine-id to Disk...42controller # [7504940.398931] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys43controller # [7504940.410195] controller systemd[1]: Finished Flush Journal to Persistent Storage.44controller # [7504940.411620] controller systemd[1]: Starting Create System Files and Directories...45controller # [7504940.429162] controller systemd-tmpfiles[153]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted46controller # [7504940.429374] controller systemd-tmpfiles[153]: fchmod() of /var/log/journal failed: Operation not permitted47controller # [7504940.429521] controller systemd-tmpfiles[153]: fchmod() of /var/log/journal/e75dc48847bc44bea34335b9fdd3fed7 failed: Operation not permitted48controller # [7504940.429748] controller systemd-tmpfiles[153]: fchmod() of /run/log/journal failed: Operation not permitted49controller # [7504940.431251] controller systemd[1]: Finished Create System Files and Directories.50controller # [7504940.432215] controller systemd[1]: Starting Rebuild Journal Catalog...51controller # [7504940.432905] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...52controller # [7504940.446061] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.53controller # [7504940.450740] controller systemd[1]: Finished Save Transient machine-id to Disk.54controller # [7504940.450956] controller systemd[1]: Finished Rebuild Journal Catalog.55controller # [7504940.452523] controller systemd[1]: Starting Update is Completed...56controller # [7504940.463823] controller systemd[1]: Finished Update is Completed.57controller # [7504940.530694] controller systemd[1]: Finished Firewall.58controller # [7504940.530838] controller systemd[1]: Reached target Preparation for Network.59controller # [7504940.531058] controller systemd[1]: Listening on Network Management Resolve Hook Socket.60controller # [7504940.532063] controller systemd[1]: Starting Network Management...61controller # [7504940.905144] controller systemd-networkd[225]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted62controller # [7504940.905232] controller systemd-networkd[225]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted63controller # [7504940.912346] 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.64controller # [7504940.912506] 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.65controller # [7504940.912652] controller systemd-networkd[225]: lo: Link UP66controller # [7504940.912655] controller systemd-networkd[225]: lo: Gained carrier67controller # [7504940.912851] controller systemd-networkd[225]: eth1: Configuring with /etc/systemd/network/40-eth1.network.68controller # [7504940.913207] controller systemd[1]: Started Network Management.69controller # [7504940.949074] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...70controller # [7504940.949637] controller systemd-networkd[225]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.71controller # [7504940.949981] controller systemd-networkd[225]: wg-star: netdev ready72controller # [7504940.950454] controller systemd-networkd[225]: eth1: Link UP73controller # [7504940.950823] controller systemd-networkd[225]: eth1: Gained carrier74controller # [7504940.997014] controller systemd-networkd[225]: wg-star: Link UP75controller # [7504940.997019] controller systemd-networkd[225]: wg-star: Gained carrier76controller # [7504940.997422] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.77controller # [7504941.077500] controller systemd-resolved[131]: Positive Trust Anchors:78controller # [7504941.077511] controller systemd-resolved[131]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d79controller # [7504941.077514] controller systemd-resolved[131]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b1680controller # [7504941.077550] controller systemd-resolved[131]: 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 test81controller # [7504941.099344] controller systemd-resolved[131]: Using system hostname 'controller'.82peer1 # [7504940.355855] peer1 systemd-journald[95]: Journal started83peer1 # [7504940.355916] peer1 systemd-journald[95]: Runtime Journal (/run/log/journal/35452c24d07644489f884994d0e1731c) is 8M, max 2.5G, 2.4G free.84peer1 # [7504940.359416] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.85controller # [7504941.101562] controller systemd[1]: Started Network Name Resolution.86peer1 # [7504940.369799] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...87controller # [7504941.101645] controller systemd[1]: Reached target Network.88peer1 # [7504940.370949] peer1 systemd[1]: Starting Network Name Resolution...89controller # [7504941.101725] controller systemd[1]: Reached target System Initialization.90peer1 # [7504940.371845] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...91controller # [7504941.101843] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.92controller # [7504941.101874] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container93controller # [7504941.101898] controller systemd[1]: Started Daily Cleanup of Temporary Directories.94controller # [7504941.101918] controller systemd[1]: Reached target Path Units.95controller # [7504941.101960] controller systemd[1]: Reached target Timer Units.96peer1 # [7504940.378256] peer1 systemd-journald[95]: Time spent on flushing to /var/log/journal/35452c24d07644489f884994d0e1731c is 3.081ms for 6 entries.97controller # [7504941.102135] controller systemd[1]: Listening on D-Bus System Message Bus Socket.98peer1 # [7504940.378256] peer1 systemd-journald[95]: System Journal (/var/log/journal/35452c24d07644489f884994d0e1731c) is 8M, max 4G, 3.9G free.99controller # [7504941.102274] controller systemd[1]: Listening on Nix Daemon Socket.100controller # [7504941.102456] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.101controller # [7504941.102479] controller systemd[1]: Reached target Socket Units.102controller # [7504941.102524] controller systemd[1]: Reached target Basic System.103controller # [7504941.104046] controller systemd[1]: Starting data mesher daemon...104controller # [7504941.105100] controller systemd[1]: Starting Import lastlog data into lastlog2 database...105controller # [7504941.106008] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...106controller # [7504941.107284] controller systemd[1]: Starting D-Bus System Message Bus...107controller # [7504941.162428] controller systemd[1]: Finished Import lastlog data into lastlog2 database.108controller # [7504941.273497] controller systemd[1]: Started Name Service Cache Daemon (nsncd).109controller # [7504941.273973] controller nsncd[232]: Sep 03 20:06:07.326 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"110controller # [7504941.273604] controller systemd[1]: Reached target Host and Network Name Lookups.111controller # [7504941.273691] controller systemd[1]: Reached target User and Group Name Lookups.112controller # [7504941.275780] controller systemd[1]: Starting User Login Management...113controller # [7504941.277057] controller systemd[1]: Starting Permit User Sessions...114peer1 # [7504940.387588] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.115peer1 # [7504940.387832] peer1 systemd[1]: Reached target Preparation for Local File Systems.116peer1 # [7504940.387929] peer1 systemd[1]: Reached target Local File Systems.117peer1 # [7504940.388742] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.118peer1 # [7504940.388791] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container119peer1 # [7504940.389642] peer1 systemd[1]: Starting Save Transient machine-id to Disk...120peer1 # [7504940.389675] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys121peer1 # [7504940.402789] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.122peer1 # [7504940.404291] peer1 systemd[1]: Starting Create System Files and Directories...123peer1 # [7504940.423666] peer1 systemd-tmpfiles[144]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted124peer1 # [7504940.423884] peer1 systemd-tmpfiles[144]: fchmod() of /var/log/journal failed: Operation not permitted125peer1 # [7504940.424051] peer1 systemd-tmpfiles[144]: fchmod() of /var/log/journal/35452c24d07644489f884994d0e1731c failed: Operation not permitted126peer1 # [7504940.424286] peer1 systemd-tmpfiles[144]: fchmod() of /run/log/journal failed: Operation not permitted127peer1 # [7504940.425834] peer1 systemd[1]: Finished Create System Files and Directories.128peer1 # [7504940.427019] peer1 systemd[1]: Starting Rebuild Journal Catalog...129peer1 # [7504940.427953] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...130peer1 # [7504940.441398] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.131peer1 # [7504940.447569] peer1 systemd[1]: Finished Rebuild Journal Catalog.132peer1 # [7504940.449018] peer1 systemd[1]: Starting Update is Completed...133peer1 # [7504940.451083] peer1 systemd[1]: Finished Save Transient machine-id to Disk.134peer1 # [7504940.459208] peer1 systemd[1]: Finished Update is Completed.135peer1 # [7504940.512475] peer1 systemd[1]: Finished Firewall.136peer1 # [7504940.512569] peer1 systemd[1]: Reached target Preparation for Network.137peer1 # [7504940.512791] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.138peer1 # [7504940.513829] peer1 systemd[1]: Starting Network Management...139peer1 # [7504940.890921] peer1 systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted140peer1 # [7504940.891011] peer1 systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted141peer1 # [7504940.899572] 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.142peer1 # [7504940.899743] 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.143peer1 # [7504940.899904] peer1 systemd-networkd[213]: lo: Link UP144peer1 # [7504940.899909] peer1 systemd-networkd[213]: lo: Gained carrier145peer1 # [7504940.900139] peer1 systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.146peer1 # [7504940.900514] peer1 systemd[1]: Started Network Management.147peer1 # [7504940.948843] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...148peer1 # [7504940.949637] peer1 systemd-networkd[213]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.149peer1 # [7504940.949984] peer1 systemd-networkd[213]: wg-star: netdev ready150peer1 # [7504940.950775] peer1 systemd-networkd[213]: eth1: Link UP151peer1 # [7504940.951003] peer1 systemd-networkd[213]: eth1: Gained carrier152peer1 # [7504940.981391] peer1 systemd-networkd[213]: wg-star: Link UP153peer1 # [7504940.981397] peer1 systemd-networkd[213]: wg-star: Gained carrier154peer1 # [7504940.981872] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.155peer1 # [7504941.074514] peer1 systemd-resolved[121]: Positive Trust Anchors:156peer1 # [7504941.074524] peer1 systemd-resolved[121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d157peer1 # [7504941.074527] peer1 systemd-resolved[121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16158peer1 # [7504941.074562] peer1 systemd-resolved[121]: 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 test159peer1 # [7504941.097763] peer1 systemd-resolved[121]: Using system hostname 'peer1'.160peer1 # [7504941.099663] peer1 systemd[1]: Started Network Name Resolution.161peer1 # [7504941.099755] peer1 systemd[1]: Reached target Network.162peer1 # [7504941.099834] peer1 systemd[1]: Reached target System Initialization.163peer1 # [7504941.099958] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.164peer1 # [7504941.099992] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.165peer1 # [7504941.100038] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container166peer1 # [7504941.100063] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.167peer1 # [7504941.100085] peer1 systemd[1]: Reached target Path Units.168peer1 # [7504941.100349] peer1 systemd[1]: Reached target Timer Units.169peer1 # [7504941.100562] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.170peer1 # [7504941.100715] peer1 systemd[1]: Listening on Nix Daemon Socket.171peer1 # [7504941.100906] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.172peer1 # [7504941.100933] peer1 systemd[1]: Reached target Socket Units.173peer1 # [7504941.100985] peer1 systemd[1]: Reached target Basic System.174peer1 # [7504941.102616] peer1 systemd[1]: Starting data mesher daemon...175peer1 # [7504941.103654] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...176peer1 # [7504941.104891] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...177peer1 # [7504941.106351] peer1 systemd[1]: Starting D-Bus System Message Bus...178peer1 # [7504941.162283] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.179peer1 # [7504941.271653] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).180peer1 # [7504941.271757] peer1 systemd[1]: Reached target Host and Network Name Lookups.181peer1 # [7504941.271834] peer1 nsncd[220]: Sep 03 20:06:07.324 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"182peer1 # [7504941.271854] peer1 systemd[1]: Reached target User and Group Name Lookups.183peer1 # [7504941.273834] peer1 systemd[1]: Starting User Login Management...184peer1 # [7504941.275229] peer1 systemd[1]: Starting Permit User Sessions...185controller # [7504941.329519] controller systemd[1]: Finished Permit User Sessions.186controller # [7504941.331108] controller systemd[1]: Started Console Getty.187controller # [7504941.331182] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0188controller # [7504941.331223] controller systemd[1]: Reached target Login Prompts.189controller # [7504941.356754] controller dbus-broker-launch[233]: Looking up NSS user entry for 'systemd-timesync'...190controller # [7504941.358082] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.191controller # [7504941.360093] controller dbus-broker-launch[233]: NSS returned no entry for 'systemd-timesync'192controller # [7504941.360093] controller dbus-broker-launch[233]: Invalid user-name in /nix/store/xsr87dgy2kj3mg3fn4f91cnqa6qwvd21-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"193controller # [7504941.360687] controller systemd[1]: Started D-Bus System Message Bus.194controller # [7504941.368419] controller dbus-broker-launch[233]: Ready195peer1 # [7504941.327953] peer1 systemd[1]: Finished Permit User Sessions.196peer1 # [7504941.329654] peer1 systemd[1]: Started Console Getty.197peer1 # [7504941.329729] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0198peer1 # [7504941.329778] peer1 systemd[1]: Reached target Login Prompts.199peer1 # [7504941.348135] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.200peer1 # [7504941.358651] peer1 dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...201peer1 # [7504941.360284] peer1 dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'202peer1 # [7504941.360284] peer1 dbus-broker-launch[221]: Invalid user-name in /nix/store/xsr87dgy2kj3mg3fn4f91cnqa6qwvd21-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"203peer1 # [7504941.360900] peer1 systemd[1]: Started D-Bus System Message Bus.204peer1 # [7504941.369228] peer1 dbus-broker-launch[221]: Ready205peer1 # [7504941.589475] peer1 data-mesher[218]: time=2026-09-03T20:06:07.642Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]206peer1 # [7504941.590481] peer1 data-mesher[218]: time=2026-09-03T20:06:07.643Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466: [/dns/controller.clan/tcp/7946]} {12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2: [/dns/peer1.clan/tcp/7946]} {12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2207peer1 # [7504941.590481] peer1 data-mesher[218]: time=2026-09-03T20:06:07.643Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml208peer1 # [7504941.601885] peer1 data-mesher[218]: time=2026-09-03T20:06:07.655Z level=INFO msg="checking file integrity"209peer1 # [7504941.602002] peer1 data-mesher[218]: time=2026-09-03T20:06:07.655Z level=INFO msg="file integrity check complete"210peer1 # [7504941.605676] peer1 data-mesher[218]: time=2026-09-03T20:06:07.658Z level=INFO msg="libp2p host created" peer_id=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 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::35fa:39:d6ca:cb57/tcp/7946]"211peer1 # [7504941.605757] peer1 data-mesher[218]: time=2026-09-03T20:06:07.658Z level=INFO msg="registered HTTP route" method=GET path=/files212peer1 # [7504941.605757] peer1 data-mesher[218]: time=2026-09-03T20:06:07.658Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name213peer1 # [7504941.605757] peer1 data-mesher[218]: time=2026-09-03T20:06:07.658Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name214peer1 # [7504941.605757] peer1 data-mesher[218]: time=2026-09-03T20:06:07.658Z level=INFO msg="starting server"215peer1 # [7504941.605931] peer1 data-mesher[218]: time=2026-09-03T20:06:07.658Z level=INFO msg="waiting for DHT to populate" delay=10s216peer1 # [7504941.605931] peer1 data-mesher[218]: time=2026-09-03T20:06:07.659Z level=INFO msg="HTTP server listening" address=[::1]:7331217peer1 # [7504941.606395] peer1 data-mesher[218]: time=2026-09-03T20:06:07.659Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331218peer1 # [7504941.612694] peer1 data-mesher[218]: time=2026-09-03T20:06:07.665Z level=INFO msg="peer connected" peer_id=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 remote_addr=/ip4/192.168.1.1/tcp/7946219peer1 # [7504941.614411] peer1 data-mesher[218]: time=2026-09-03T20:06:07.667Z level=INFO msg="peer connected" peer_id=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 remote_addr=/ip4/192.168.1.1/tcp/47494220peer1 # [7504941.732130] peer1 systemd-logind[238]: New seat seat0.221peer1 # [7504941.732318] peer1 systemd[1]: Started User Login Management.222peer1 # [7504941.734438] peer1 systemd[1]: Starting linger-users.service...223peer1 # [7504941.770006] peer1 systemd[1]: linger-users.service: Deactivated successfully.224peer1 # [7504941.770127] peer1 systemd[1]: Finished linger-users.service.225controller # [7504941.589176] controller data-mesher[230]: time=2026-09-03T20:06:07.642Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]226controller # [7504941.590247] controller data-mesher[230]: time=2026-09-03T20:06:07.643Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466: [/dns/controller.clan/tcp/7946]} {12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2: [/dns/peer1.clan/tcp/7946]} {12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466227controller # [7504941.590314] controller data-mesher[230]: time=2026-09-03T20:06:07.643Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml228controller # [7504941.601754] controller data-mesher[230]: time=2026-09-03T20:06:07.654Z level=INFO msg="checking file integrity"229controller # [7504941.601870] controller data-mesher[230]: time=2026-09-03T20:06:07.655Z level=INFO msg="file integrity check complete"230controller # [7504941.605738] controller data-mesher[230]: time=2026-09-03T20:06:07.658Z level=INFO msg="libp2p host created" peer_id=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 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::8347:d476:2a4b:65b7/tcp/7946]"231controller # [7504941.605786] controller data-mesher[230]: time=2026-09-03T20:06:07.658Z level=INFO msg="registered HTTP route" method=GET path=/files232controller # [7504941.605786] controller data-mesher[230]: time=2026-09-03T20:06:07.658Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name233controller # [7504941.605786] controller data-mesher[230]: time=2026-09-03T20:06:07.658Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name234controller # [7504941.605786] controller data-mesher[230]: time=2026-09-03T20:06:07.658Z level=INFO msg="starting server"235controller # [7504941.606030] controller data-mesher[230]: time=2026-09-03T20:06:07.659Z level=INFO msg="HTTP server listening" address=[::1]:7331236controller # [7504941.606073] controller data-mesher[230]: time=2026-09-03T20:06:07.659Z level=INFO msg="waiting for DHT to populate" delay=10s237controller # [7504941.606106] controller data-mesher[230]: time=2026-09-03T20:06:07.659Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331238controller # [7504941.614198] controller data-mesher[230]: time=2026-09-03T20:06:07.666Z level=INFO msg="peer connected" peer_id=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 remote_addr=/ip4/192.168.1.2/tcp/7946239controller # [7504941.614918] controller data-mesher[230]: time=2026-09-03T20:06:07.668Z level=INFO msg="peer connected" peer_id=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 remote_addr=/ip4/192.168.1.2/tcp/7946240controller # [7504941.732130] controller systemd-logind[250]: New seat seat0.241controller # [7504941.732317] controller systemd[1]: Started User Login Management.242controller # [7504941.734554] controller systemd[1]: Starting linger-users.service...243controller # [7504941.769635] controller systemd[1]: linger-users.service: Deactivated successfully.244controller # [7504941.769856] controller systemd[1]: Finished linger-users.service.245peer1 # [7504942.532504] peer1 systemd-networkd[213]: eth1: Gained IPv6LL246controller # [7504942.688250] controller systemd-networkd[225]: eth1: Gained IPv6LL247controller: still waiting for container 'controller' to reach ready state...248peer1 # [7504951.606424] peer1 data-mesher[218]: time=2026-09-03T20:06:17.659Z level=INFO msg="performing state exchange with peers on join" count=1249peer1 # [7504951.607137] peer1 data-mesher[218]: time=2026-09-03T20:06:17.659Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s250peer1 # [7504951.607636] peer1 data-mesher[218]: time=2026-09-03T20:06:17.660Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466251peer1 # [7504951.607636] peer1 data-mesher[218]: time=2026-09-03T20:06:17.660Z level=INFO msg="state exchange complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s252peer1 # [7504951.607758] peer1 data-mesher[218]: time=2026-09-03T20:06:17.660Z level=INFO msg="received state sync from peer" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466253peer1 # [7504951.607758] peer1 data-mesher[218]: time=2026-09-03T20:06:17.660Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466254peer1 # [7504951.607758] peer1 data-mesher[218]: time=2026-09-03T20:06:17.660Z level=INFO msg="server started"255peer1 # [7504951.607891] peer1 data-mesher[218]: time=2026-09-03T20:06:17.660Z level=INFO msg="starting expired-file sweeper" interval=1m0s256peer1 # [7504951.608239] peer1 systemd[1]: Started data mesher daemon.257peer1 # [7504951.610083] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...258peer1 # [7504951.772543] peer1 data-mesher[218]: time=2026-09-03T20:06:17.825Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk status=204259peer1 # [7504951.772763] peer1 dm-wg-star-publish[283]: Status: 204 No Content260peer1 # [7504951.774355] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...261peer1 # [7504951.776440] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.262peer1 # [7504951.776615] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.263peer1 # [7504951.777141] peer1 systemd[1]: Reached target Multi-User System.264controller: (finished: waiting for unit data-mesher.service, in 12.67 seconds)265peer1: waiting for unit data-mesher.service266peer1: (finished: waiting for unit data-mesher.service, in 0.02 seconds)267??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.268 File "/nix/store/pg5lbaicpvf34wrdmfh8riwp8avk7lk6-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39269controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller270??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.271 File "/nix/store/pg5lbaicpvf34wrdmfh8riwp8avk7lk6-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39272controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.01 seconds)273peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller274controller # [7504951.606993] controller data-mesher[230]: time=2026-09-03T20:06:17.660Z level=INFO msg="performing state exchange with peers on join" count=1275controller # [7504951.607662] controller data-mesher[230]: time=2026-09-03T20:06:17.660Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s276controller # [7504951.607662] controller data-mesher[230]: time=2026-09-03T20:06:17.660Z level=INFO msg="received state sync from peer" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2277controller # [7504951.607662] controller data-mesher[230]: time=2026-09-03T20:06:17.660Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2278controller # [7504951.607877] controller data-mesher[230]: time=2026-09-03T20:06:17.661Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2279controller # [7504951.607877] controller data-mesher[230]: time=2026-09-03T20:06:17.661Z level=INFO msg="state exchange complete" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s280controller # [7504951.607997] controller data-mesher[230]: time=2026-09-03T20:06:17.661Z level=INFO msg="server started"281controller # [7504951.608105] controller data-mesher[230]: time=2026-09-03T20:06:17.661Z level=INFO msg="starting expired-file sweeper" interval=1m0s282controller # [7504951.608193] controller systemd[1]: Started data mesher daemon.283controller # [7504951.610311] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...284controller # [7504951.687486] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...285controller # [7504951.737763] controller data-mesher[230]: time=2026-09-03T20:06:17.790Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204286controller # [7504951.737919] controller dm-wg-star-publish[294]: Status: 204 No Content287controller # [7504951.742094] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.288controller # [7504951.742862] controller systemd[1]: Reached target Multi-User System.289controller # [7504951.777530] controller dm-wg-star-reconfig[320]: No peer data available yet, skipping290controller # [7504951.778915] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.291controller # [7504951.816275] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.292controller # [7504951.816799] controller systemd[1]: Startup finished in 11.931s.293peer1 # [7504951.905992] peer1 dm-wg-star-reconfig[311]: No controller data available yet, skipping294peer1 # [7504951.907338] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.295peer1 # [7504951.907498] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.296peer1 # [7504951.908046] peer1 systemd[1]: Startup finished in 12.040s.297peer1 # [7504956.608930] peer1 data-mesher[218]: time=2026-09-03T20:06:22.662Z level=DEBUG msg="attempting push/pull" peer_count=1298peer1 # [7504956.609580] peer1 data-mesher[218]: time=2026-09-03T20:06:22.662Z level=INFO msg="received state sync from peer" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466299peer1 # [7504956.609580] peer1 data-mesher[218]: time=2026-09-03T20:06:22.662Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466300peer1 # [7504956.609580] peer1 data-mesher[218]: time=2026-09-03T20:06:22.662Z level=DEBUG msg="new file detected" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller301peer1 # [7504956.609580] peer1 data-mesher[218]: time=2026-09-03T20:06:22.662Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s302peer1 # [7504956.609580] peer1 data-mesher[218]: time=2026-09-03T20:06:22.662Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller303peer1 # [7504956.609580] peer1 data-mesher[218]: time=2026-09-03T20:06:22.662Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-03 20:06:17.734 +0000 UTC" signed_by="nPzNL9EwkbvuZIKP5H2nx8SyUYpgCLnMm1a4dWKOCpQ=" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466304peer1 # [7504956.611358] peer1 data-mesher[218]: time=2026-09-03T20:06:22.664Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466305peer1 # [7504956.611445] peer1 data-mesher[218]: time=2026-09-03T20:06:22.664Z level=DEBUG msg="new file detected" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller306peer1 # [7504956.611445] peer1 data-mesher[218]: time=2026-09-03T20:06:22.664Z level=INFO msg="state exchange complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s307peer1 # [7504956.611445] peer1 data-mesher[218]: time=2026-09-03T20:06:22.664Z level=DEBUG msg="push/pull successful" interval=5s308peer1 # [7504956.612662] peer1 data-mesher[218]: time=2026-09-03T20:06:22.665Z level=INFO msg="received file request" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk309peer1 # [7504956.615041] peer1 data-mesher[218]: time=2026-09-03T20:06:22.668Z level=INFO msg="file transfer complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk310peer1 # [7504956.618516] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...311peer1 # [7504956.627636] peer1 data-mesher[218]: time=2026-09-03T20:06:22.680Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-03 20:06:17.734 +0000 UTC" signed_by="nPzNL9EwkbvuZIKP5H2nx8SyUYpgCLnMm1a4dWKOCpQ=" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 written=true elapsed=18.199283ms312peer1 # [7504956.782590] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.313peer1 # [7504956.782740] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.314controller # [7504956.608261] controller data-mesher[230]: time=2026-09-03T20:06:22.661Z level=DEBUG msg="attempting push/pull" peer_count=1315controller # [7504956.608944] controller data-mesher[230]: time=2026-09-03T20:06:22.661Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s316controller # [7504956.609496] controller data-mesher[230]: time=2026-09-03T20:06:22.662Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2317controller # [7504956.609619] controller data-mesher[230]: time=2026-09-03T20:06:22.662Z level=INFO msg="received state sync from peer" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2318controller # [7504956.609619] controller data-mesher[230]: time=2026-09-03T20:06:22.662Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2319controller # [7504956.609912] controller data-mesher[230]: time=2026-09-03T20:06:22.663Z level=INFO msg="received file request" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/controller320controller # [7504956.610866] controller data-mesher[230]: time=2026-09-03T20:06:22.663Z level=DEBUG msg="new file detected" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk321controller # [7504956.610931] controller data-mesher[230]: time=2026-09-03T20:06:22.664Z level=DEBUG msg="new file detected" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk322controller # [7504956.610931] controller data-mesher[230]: time=2026-09-03T20:06:22.664Z level=INFO msg="state exchange complete" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s323controller # [7504956.611060] controller data-mesher[230]: time=2026-09-03T20:06:22.664Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk324controller # [7504956.611270] controller data-mesher[230]: time=2026-09-03T20:06:22.664Z level=DEBUG msg="push/pull successful" interval=5s325controller # [7504956.611337] controller data-mesher[230]: time=2026-09-03T20:06:22.664Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk signed_at="2026-09-03 20:06:17.822 +0000 UTC" signed_by="mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk=" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2326controller # [7504956.612428] controller data-mesher[230]: time=2026-09-03T20:06:22.665Z level=INFO msg="file transfer complete" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/controller327controller # [7504956.622897] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...328controller # [7504956.627592] controller data-mesher[230]: time=2026-09-03T20:06:22.680Z level=INFO msg="download complete" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk signed_at="2026-09-03 20:06:17.822 +0000 UTC" signed_by="mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk=" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 written=true elapsed=16.418859ms329controller # [7504956.780707] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.330controller # [7504956.781040] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.331peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.05 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.02 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::8347:d476:2a4b:65b7339peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8347:d476:2a4b:65b7, in 0.01 seconds)340controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::35fa:0039:d6ca:cb57341controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::35fa:0039:d6ca:cb57, in 0.01 seconds)342controller: must succeed: wg show wg-star peers | wc -l343controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.01 seconds)344peer2: systemd-nspawn running (pid 779)345peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...346peer2: waiting for unit data-mesher.service347nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE348nixos-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.349Note: 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.350░ Spawning container peer2 on /build/vm-state-peer2.351peer2 # No journal files were found.352peer2 # No journal boot entry found for the specified boot (+0).353peer2 # [7504958.123811] peer2 systemd-journald[95]: Journal started354peer2 # [7504958.123867] peer2 systemd-journald[95]: Runtime Journal (/run/log/journal/9ca6bece9d364762b8f38b9b60384b5f) is 8M, max 2.5G, 2.4G free.355peer2 # [7504958.127984] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...356peer2 # [7504958.128736] peer2 systemd[1]: Starting Network Name Resolution...357peer2 # [7504958.129364] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...358peer2 # [7504958.138113] peer2 systemd-journald[95]: Time spent on flushing to /var/log/journal/9ca6bece9d364762b8f38b9b60384b5f is 1.553ms for 5 entries.359peer2 # [7504958.138113] peer2 systemd-journald[95]: System Journal (/var/log/journal/9ca6bece9d364762b8f38b9b60384b5f) is 8M, max 4G, 3.9G free.360peer2 # [7504958.144647] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.361peer2 # [7504958.144859] peer2 systemd[1]: Reached target Preparation for Local File Systems.362peer2 # [7504958.144941] peer2 systemd[1]: Reached target Local File Systems.363peer2 # [7504958.145640] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.364peer2 # [7504958.145684] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container365peer2 # [7504958.146531] peer2 systemd[1]: Starting Save Transient machine-id to Disk...366peer2 # [7504958.146561] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys367peer2 # [7504958.158467] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.368peer2 # [7504958.159992] peer2 systemd[1]: Starting Create System Files and Directories...369peer2 # [7504958.176940] peer2 systemd-tmpfiles[140]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted370peer2 # [7504958.177145] peer2 systemd-tmpfiles[140]: fchmod() of /var/log/journal failed: Operation not permitted371peer2 # [7504958.177291] peer2 systemd-tmpfiles[140]: fchmod() of /var/log/journal/9ca6bece9d364762b8f38b9b60384b5f failed: Operation not permitted372peer2 # [7504958.177510] peer2 systemd-tmpfiles[140]: fchmod() of /run/log/journal failed: Operation not permitted373peer2 # [7504958.178786] peer2 systemd[1]: Finished Create System Files and Directories.374peer2 # [7504958.179832] peer2 systemd[1]: Starting Rebuild Journal Catalog...375peer2 # [7504958.180629] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...376peer2 # [7504958.194875] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.377peer2 # [7504958.203871] peer2 systemd[1]: Finished Rebuild Journal Catalog.378peer2 # [7504958.205325] peer2 systemd[1]: Starting Update is Completed...379peer2 # [7504958.218157] peer2 systemd[1]: Finished Update is Completed.380peer2 # [7504958.284626] peer2 systemd[1]: Finished Firewall.381peer2 # [7504958.285020] peer2 systemd[1]: Reached target Preparation for Network.382peer2 # [7504958.285277] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.383peer2 # [7504958.286513] peer2 systemd[1]: Starting Network Management...384peer2 # [7504958.483874] peer2 systemd[1]: Finished Save Transient machine-id to Disk.385peer2 # [7504958.691532] peer2 systemd-networkd[212]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted386peer2 # [7504958.691620] peer2 systemd-networkd[212]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted387peer2 # [7504958.698842] peer2 systemd-networkd[212]: /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.388peer2 # [7504958.699003] peer2 systemd-networkd[212]: /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.389peer2 # [7504958.699143] peer2 systemd-networkd[212]: lo: Link UP390peer2 # [7504958.699148] peer2 systemd-networkd[212]: lo: Gained carrier391peer2 # [7504958.699326] peer2 systemd-networkd[212]: eth1: Configuring with /etc/systemd/network/40-eth1.network.392peer2 # [7504958.699747] peer2 systemd[1]: Started Network Management.393peer2 # [7504958.701555] peer2 systemd-networkd[212]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.394peer2 # [7504958.701826] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...395peer2 # [7504958.701860] peer2 systemd-networkd[212]: wg-star: netdev ready396peer2 # [7504958.702233] peer2 systemd-networkd[212]: eth1: Link UP397peer2 # [7504958.702441] peer2 systemd-networkd[212]: eth1: Gained carrier398peer2 # [7504958.740796] peer2 systemd-networkd[212]: wg-star: Link UP399peer2 # [7504958.740802] peer2 systemd-networkd[212]: wg-star: Gained carrier400peer2 # [7504958.750491] peer2 systemd-resolved[116]: Positive Trust Anchors:401peer2 # [7504958.750506] peer2 systemd-resolved[116]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d402peer2 # [7504958.750508] peer2 systemd-resolved[116]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16403peer2 # [7504958.750544] 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 test404peer2 # [7504958.753320] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.405peer2 # [7504958.772199] peer2 systemd-resolved[116]: Using system hostname 'peer2'.406peer2 # [7504958.773756] peer2 systemd[1]: Started Network Name Resolution.407peer2 # [7504958.773891] peer2 systemd[1]: Reached target Network.408peer2 # [7504958.774004] peer2 systemd[1]: Reached target System Initialization.409peer2 # [7504958.774173] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.410peer2 # [7504958.774232] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.411peer2 # [7504958.774301] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container412peer2 # [7504958.774342] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.413peer2 # [7504958.774384] peer2 systemd[1]: Reached target Path Units.414peer2 # [7504958.774457] peer2 systemd[1]: Reached target Timer Units.415peer2 # [7504958.774677] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.416peer2 # [7504958.774880] peer2 systemd[1]: Listening on Nix Daemon Socket.417peer2 # [7504958.775095] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.418peer2 # [7504958.775153] peer2 systemd[1]: Reached target Socket Units.419peer2 # [7504958.775229] peer2 systemd[1]: Reached target Basic System.420peer2 # [7504958.777255] peer2 systemd[1]: Starting data mesher daemon...421peer2 # [7504958.778564] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...422peer2 # [7504958.779929] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...423peer2 # [7504958.782123] peer2 systemd[1]: Starting D-Bus System Message Bus...424peer2 # [7504958.829884] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.425peer2 # [7504958.919012] peer2 nsncd[220]: Sep 03 20:06:24.972 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"426peer2 # [7504958.919101] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).427peer2 # [7504958.919194] peer2 systemd[1]: Reached target Host and Network Name Lookups.428peer2 # [7504958.919302] peer2 systemd[1]: Reached target User and Group Name Lookups.429peer2 # [7504958.921208] peer2 systemd[1]: Starting User Login Management...430peer2 # [7504958.922543] peer2 systemd[1]: Starting Permit User Sessions...431peer2 # [7504958.960028] peer2 systemd[1]: Finished Permit User Sessions.432peer2 # [7504958.961566] peer2 systemd[1]: Started Console Getty.433peer2 # [7504958.961645] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0434peer2 # [7504958.961687] peer2 systemd[1]: Reached target Login Prompts.435peer2 # [7504958.993858] peer2 dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...436peer2 # [7504958.995206] peer2 dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'437peer2 # [7504958.995206] peer2 dbus-broker-launch[221]: Invalid user-name in /nix/store/xsr87dgy2kj3mg3fn4f91cnqa6qwvd21-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"438peer2 # [7504958.995669] peer2 systemd[1]: Started D-Bus System Message Bus.439peer2 # [7504959.004720] peer2 dbus-broker-launch[221]: Ready440peer2 # [7504959.115282] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.441peer2 # [7504959.248893] peer2 data-mesher[218]: time=2026-09-03T20:06:25.301Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]442peer2 # [7504959.249936] peer2 data-mesher[218]: time=2026-09-03T20:06:25.303Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466: [/dns/controller.clan/tcp/7946]} {12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2: [/dns/peer1.clan/tcp/7946]} {12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt443peer2 # [7504959.249936] peer2 data-mesher[218]: time=2026-09-03T20:06:25.303Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml444peer2 # [7504959.261787] peer2 data-mesher[218]: time=2026-09-03T20:06:25.314Z level=INFO msg="checking file integrity"445peer2 # [7504959.261900] peer2 data-mesher[218]: time=2026-09-03T20:06:25.315Z level=INFO msg="file integrity check complete"446peer2 # [7504959.266328] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="libp2p host created" peer_id=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt 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::3e0:aacd:42ac:6269/tcp/7946]"447peer2 # [7504959.266430] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="registered HTTP route" method=GET path=/files448peer2 # [7504959.266430] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name449peer2 # [7504959.266430] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name450peer2 # [7504959.266430] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="starting server"451peer2 # [7504959.266649] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="waiting for DHT to populate" delay=10s452peer2 # [7504959.266649] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="HTTP server listening" address=[::1]:7331453peer2 # [7504959.266649] peer2 data-mesher[218]: time=2026-09-03T20:06:25.319Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331454peer2 # [7504959.273605] peer2 data-mesher[218]: time=2026-09-03T20:06:25.326Z level=INFO msg="peer connected" peer_id=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 remote_addr=/ip4/192.168.1.1/tcp/7946455peer2 # [7504959.281864] peer2 data-mesher[218]: time=2026-09-03T20:06:25.335Z level=INFO msg="peer connected" peer_id=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 remote_addr=/ip4/192.168.1.2/tcp/7946456peer2 # [7504959.452181] peer2 systemd-logind[238]: New seat seat0.457peer2 # [7504959.452408] peer2 systemd[1]: Started User Login Management.458peer2 # [7504959.453631] peer2 systemd[1]: Starting linger-users.service...459controller # [7504959.275295] controller data-mesher[230]: time=2026-09-03T20:06:25.328Z level=INFO msg="peer connected" peer_id=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt remote_addr=/ip4/192.168.1.3/tcp/7946460peer1 # [7504959.283720] peer1 data-mesher[218]: time=2026-09-03T20:06:25.336Z level=INFO msg="peer connected" peer_id=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt remote_addr=/ip4/192.168.1.3/tcp/7946461peer2 # [7504959.513936] peer2 systemd[1]: linger-users.service: Deactivated successfully.462peer2 # [7504959.514128] peer2 systemd[1]: Finished linger-users.service.463peer2 # [7504960.576118] peer2 systemd-networkd[212]: eth1: Gained IPv6LL464peer1 # [7504961.612555] peer1 data-mesher[218]: time=2026-09-03T20:06:27.665Z level=DEBUG msg="attempting push/pull" peer_count=1465peer1 # [7504961.612555] peer1 data-mesher[218]: time=2026-09-03T20:06:27.665Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s466peer1 # [7504961.613777] peer1 data-mesher[218]: time=2026-09-03T20:06:27.666Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466467peer1 # [7504961.614113] peer1 data-mesher[218]: time=2026-09-03T20:06:27.667Z level=INFO msg="state exchange complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s468peer1 # [7504961.614179] peer1 data-mesher[218]: time=2026-09-03T20:06:27.667Z level=DEBUG msg="push/pull successful" interval=5s469peer2 # [7504961.612957] peer2 data-mesher[218]: time=2026-09-03T20:06:27.666Z level=INFO msg="received state sync from peer" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466470peer2 # [7504961.612957] peer2 data-mesher[218]: time=2026-09-03T20:06:27.666Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466471peer2 # [7504961.613392] peer2 data-mesher[218]: time=2026-09-03T20:06:27.666Z level=DEBUG msg="new file detected" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller472peer2 # [7504961.613392] peer2 data-mesher[218]: time=2026-09-03T20:06:27.666Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller473peer2 # [7504961.613392] peer2 data-mesher[218]: time=2026-09-03T20:06:27.666Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-03 20:06:17.734 +0000 UTC" signed_by="nPzNL9EwkbvuZIKP5H2nx8SyUYpgCLnMm1a4dWKOCpQ=" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466474peer2 # [7504961.613735] peer2 data-mesher[218]: time=2026-09-03T20:06:27.666Z level=DEBUG msg="new file detected" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk475peer2 # [7504961.613853] peer2 data-mesher[218]: time=2026-09-03T20:06:27.667Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk476peer2 # [7504961.613942] peer2 data-mesher[218]: time=2026-09-03T20:06:27.667Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk signed_at="2026-09-03 20:06:17.822 +0000 UTC" signed_by="mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk=" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466477peer2 # [7504961.621058] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...478peer2 # [7504961.624934] peer2 data-mesher[218]: time=2026-09-03T20:06:27.678Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-03 20:06:17.734 +0000 UTC" signed_by="nPzNL9EwkbvuZIKP5H2nx8SyUYpgCLnMm1a4dWKOCpQ=" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 written=true elapsed=11.747716ms479peer2 # [7504961.626059] peer2 data-mesher[218]: time=2026-09-03T20:06:27.679Z level=INFO msg="download complete" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk signed_at="2026-09-03 20:06:17.822 +0000 UTC" signed_by="mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk=" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 written=true elapsed=12.169322ms480peer2 # [7504961.775942] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.481peer2 # [7504961.776294] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.482controller # [7504961.611920] controller data-mesher[230]: time=2026-09-03T20:06:27.665Z level=DEBUG msg="attempting push/pull" peer_count=1483controller # [7504961.612633] controller data-mesher[230]: time=2026-09-03T20:06:27.665Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s484controller # [7504961.613180] controller data-mesher[230]: time=2026-09-03T20:06:27.666Z level=INFO msg="received state sync from peer" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2485controller # [7504961.613180] controller data-mesher[230]: time=2026-09-03T20:06:27.666Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2486controller # [7504961.613748] controller data-mesher[230]: time=2026-09-03T20:06:27.666Z level=INFO msg="received file request" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/controller487controller # [7504961.613967] controller data-mesher[230]: time=2026-09-03T20:06:27.667Z level=INFO msg="merging remote state" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt488controller # [7504961.613967] controller data-mesher[230]: time=2026-09-03T20:06:27.667Z level=INFO msg="state exchange complete" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s489controller # [7504961.614076] controller data-mesher[230]: time=2026-09-03T20:06:27.667Z level=DEBUG msg="push/pull successful" interval=5s490controller # [7504961.615091] controller data-mesher[230]: time=2026-09-03T20:06:27.668Z level=INFO msg="received file request" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk491controller # [7504961.615155] controller data-mesher[230]: time=2026-09-03T20:06:27.668Z level=INFO msg="file transfer complete" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/controller492controller # [7504961.616477] controller data-mesher[230]: time=2026-09-03T20:06:27.669Z level=INFO msg="file transfer complete" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/mdTGU1yKJUwSmz7kSG3JBNtPNRJZpxAbjdfYYFi0VZk493peer1 # [7504966.614593] peer1 data-mesher[218]: time=2026-09-03T20:06:32.667Z level=DEBUG msg="attempting push/pull" peer_count=1494peer1 # [7504966.614593] peer1 data-mesher[218]: time=2026-09-03T20:06:32.667Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s495peer1 # [7504966.616610] peer1 data-mesher[218]: time=2026-09-03T20:06:32.669Z level=INFO msg="merging remote state" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt496peer1 # [7504966.617195] peer1 data-mesher[218]: time=2026-09-03T20:06:32.670Z level=INFO msg="state exchange complete" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s497peer1 # [7504966.617276] peer1 data-mesher[218]: time=2026-09-03T20:06:32.670Z level=DEBUG msg="push/pull successful" interval=5s498peer2 # [7504966.616021] peer2 data-mesher[218]: time=2026-09-03T20:06:32.669Z level=INFO msg="received state sync from peer" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2499peer2 # [7504966.616021] peer2 data-mesher[218]: time=2026-09-03T20:06:32.669Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2500peer2 # [7504966.616768] peer2 data-mesher[218]: time=2026-09-03T20:06:32.669Z level=INFO msg="received state sync from peer" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466501peer2 # [7504966.616768] peer2 data-mesher[218]: time=2026-09-03T20:06:32.669Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466502controller # [7504966.614567] controller data-mesher[230]: time=2026-09-03T20:06:32.667Z level=DEBUG msg="attempting push/pull" peer_count=1503controller # [7504966.615308] controller data-mesher[230]: time=2026-09-03T20:06:32.667Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s504controller # [7504966.617027] controller data-mesher[230]: time=2026-09-03T20:06:32.670Z level=INFO msg="merging remote state" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt505controller # [7504966.617295] controller data-mesher[230]: time=2026-09-03T20:06:32.670Z level=INFO msg="state exchange complete" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s506controller # [7504966.617370] controller data-mesher[230]: time=2026-09-03T20:06:32.670Z level=DEBUG msg="push/pull successful" interval=5s507peer2: still waiting for container 'peer2' to reach ready state...508peer2 # [7504969.267470] peer2 data-mesher[218]: time=2026-09-03T20:06:35.320Z level=INFO msg="performing state exchange with peers on join" count=1509peer2 # [7504969.267470] peer2 data-mesher[218]: time=2026-09-03T20:06:35.320Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s510peer2 # [7504969.268458] peer2 data-mesher[218]: time=2026-09-03T20:06:35.321Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2511peer2 # [7504969.268682] peer2 data-mesher[218]: time=2026-09-03T20:06:35.321Z level=INFO msg="state exchange complete" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s512peer2 # [7504969.268711] peer2 data-mesher[218]: time=2026-09-03T20:06:35.321Z level=INFO msg="server started"513peer2 # [7504969.268806] peer2 data-mesher[218]: time=2026-09-03T20:06:35.321Z level=INFO msg="starting expired-file sweeper" interval=1m0s514peer2 # [7504969.269021] peer2 systemd[1]: Started data mesher daemon.515peer2 # [7504969.270263] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...516peer2 # [7504969.438000] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...517peer2 # [7504969.439886] peer2 data-mesher[218]: time=2026-09-03T20:06:35.492Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck status=204518peer2 # [7504969.440049] peer2 dm-wg-star-publish[295]: Status: 204 No Content519peer2 # [7504969.442870] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.520peer2 # [7504969.480331] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.521peer2 # [7504969.480942] peer2 systemd[1]: Reached target Multi-User System.522peer2 # [7504969.577916] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.523peer1 # [7504969.268056] peer1 data-mesher[218]: time=2026-09-03T20:06:35.321Z level=INFO msg="received state sync from peer" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt524peer1 # [7504969.268056] peer1 data-mesher[218]: time=2026-09-03T20:06:35.321Z level=INFO msg="merging remote state" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt525peer2: (finished: waiting for unit data-mesher.service, in 12.66 seconds)526??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.527 File "/nix/store/pg5lbaicpvf34wrdmfh8riwp8avk7lk6-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39528controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1529??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.530 File "/nix/store/pg5lbaicpvf34wrdmfh8riwp8avk7lk6-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39531peer2 # [7504969.578120] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.532peer2 # [7504969.580744] peer2 systemd[1]: Startup finished in 11.911s.533peer1 # [7504971.617975] peer1 data-mesher[218]: time=2026-09-03T20:06:37.671Z level=DEBUG msg="attempting push/pull" peer_count=1534peer1 # [7504971.617975] peer1 data-mesher[218]: time=2026-09-03T20:06:37.671Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s535peer1 # [7504971.618797] peer1 data-mesher[218]: time=2026-09-03T20:06:37.671Z level=INFO msg="received state sync from peer" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466536peer1 # [7504971.618797] peer1 data-mesher[218]: time=2026-09-03T20:06:37.671Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466537peer1 # [7504971.619566] peer1 data-mesher[218]: time=2026-09-03T20:06:37.672Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466538peer1 # [7504971.620163] peer1 data-mesher[218]: time=2026-09-03T20:06:37.673Z level=INFO msg="state exchange complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s539peer1 # [7504971.620229] peer1 data-mesher[218]: time=2026-09-03T20:06:37.673Z level=DEBUG msg="push/pull successful" interval=5s540controller # [7504971.618027] controller data-mesher[230]: time=2026-09-03T20:06:37.671Z level=DEBUG msg="attempting push/pull" peer_count=1541controller # [7504971.618027] controller data-mesher[230]: time=2026-09-03T20:06:37.671Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s542controller # [7504971.618797] controller data-mesher[230]: time=2026-09-03T20:06:37.671Z level=INFO msg="received state sync from peer" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2543controller # [7504971.618797] controller data-mesher[230]: time=2026-09-03T20:06:37.671Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2544controller # [7504971.619481] controller data-mesher[230]: time=2026-09-03T20:06:37.672Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2545controller # [7504971.619684] controller data-mesher[230]: time=2026-09-03T20:06:37.672Z level=INFO msg="state exchange complete" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s546controller # [7504971.619750] controller data-mesher[230]: time=2026-09-03T20:06:37.672Z level=DEBUG msg="push/pull successful" interval=5s547peer1 # [7504974.269833] peer1 data-mesher[218]: time=2026-09-03T20:06:40.322Z level=INFO msg="received state sync from peer" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt548peer1 # [7504974.269833] peer1 data-mesher[218]: time=2026-09-03T20:06:40.322Z level=INFO msg="merging remote state" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt549peer1 # [7504974.270560] peer1 data-mesher[218]: time=2026-09-03T20:06:40.323Z level=DEBUG msg="new file detected" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck550peer1 # [7504974.270621] peer1 data-mesher[218]: time=2026-09-03T20:06:40.323Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck551peer1 # [7504974.270701] peer1 data-mesher[218]: time=2026-09-03T20:06:40.323Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck signed_at="2026-09-03 20:06:35.487 +0000 UTC" signed_by="EYubHgwK6l7uZn6VR692j3+CBBOiQOM/6G2/YIHkhck=" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt552peer1 # [7504974.279173] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...553peer1 # [7504974.445065] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.554peer1 # [7504974.445325] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.555peer1 # [7504974.621859] peer1 data-mesher[218]: time=2026-09-03T20:06:40.674Z level=INFO msg="download complete" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck signed_at="2026-09-03 20:06:35.487 +0000 UTC" signed_by="EYubHgwK6l7uZn6VR692j3+CBBOiQOM/6G2/YIHkhck=" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt written=true elapsed=351.067753ms556peer2 # [7504974.269040] peer2 data-mesher[218]: time=2026-09-03T20:06:40.322Z level=DEBUG msg="attempting push/pull" peer_count=2557peer2 # [7504974.269040] peer2 data-mesher[218]: time=2026-09-03T20:06:40.322Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s558peer2 # [7504974.271265] peer2 data-mesher[218]: time=2026-09-03T20:06:40.324Z level=INFO msg="received file request" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck559peer2 # [7504974.271412] peer2 data-mesher[218]: time=2026-09-03T20:06:40.324Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2560peer2 # [7504974.273696] peer2 data-mesher[218]: time=2026-09-03T20:06:40.325Z level=INFO msg="state exchange complete" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 timeout=5s561peer2 # [7504974.273804] peer2 data-mesher[218]: time=2026-09-03T20:06:40.326Z level=DEBUG msg="push/pull successful" interval=5s562peer2 # [7504974.273857] peer2 data-mesher[218]: time=2026-09-03T20:06:40.326Z level=INFO msg="file transfer complete" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck563controller: (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 7.09 seconds)564controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1565controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.01 seconds)566peer2: waiting for success: wg show wg-star peers | grep -q .567peer1 # [7504976.620757] peer1 data-mesher[218]: time=2026-09-03T20:06:42.673Z level=DEBUG msg="attempting push/pull" peer_count=1568peer1 # [7504976.620757] peer1 data-mesher[218]: time=2026-09-03T20:06:42.673Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s569peer1 # [7504976.623194] peer1 data-mesher[218]: time=2026-09-03T20:06:42.675Z level=INFO msg="received file request" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck570peer1 # [7504976.623194] peer1 data-mesher[218]: time=2026-09-03T20:06:42.675Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466571peer1 # [7504976.623469] peer1 data-mesher[218]: time=2026-09-03T20:06:42.676Z level=INFO msg="state exchange complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s572peer1 # [7504976.624138] peer1 data-mesher[218]: time=2026-09-03T20:06:42.676Z level=DEBUG msg="push/pull successful" interval=5s573peer1 # [7504976.626741] peer1 data-mesher[218]: time=2026-09-03T20:06:42.679Z level=INFO msg="file transfer complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 network="vsQC5anPX1PCAwBi4nNaJMbp8YEmqnn4tKUlmVf0Jnk=" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck574peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)575peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8347:d476:2a4b:65b7576peer2 # [7504976.621541] peer2 data-mesher[218]: time=2026-09-03T20:06:42.674Z level=INFO msg="received state sync from peer" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466577peer2 # [7504976.621541] peer2 data-mesher[218]: time=2026-09-03T20:06:42.674Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466578controller # [7504976.620952] controller data-mesher[230]: time=2026-09-03T20:06:42.673Z level=DEBUG msg="attempting push/pull" peer_count=1579controller # [7504976.620952] controller data-mesher[230]: time=2026-09-03T20:06:42.673Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s580controller # [7504976.621764] controller data-mesher[230]: time=2026-09-03T20:06:42.674Z level=INFO msg="received state sync from peer" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2581controller # [7504976.621764] controller data-mesher[230]: time=2026-09-03T20:06:42.674Z level=INFO msg="merging remote state" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2582controller # [7504976.621965] controller data-mesher[230]: time=2026-09-03T20:06:42.675Z level=DEBUG msg="new file detected" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck583controller # [7504976.623418] controller data-mesher[230]: time=2026-09-03T20:06:42.675Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck584controller # [7504976.623418] controller data-mesher[230]: time=2026-09-03T20:06:42.675Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck signed_at="2026-09-03 20:06:35.487 +0000 UTC" signed_by="EYubHgwK6l7uZn6VR692j3+CBBOiQOM/6G2/YIHkhck=" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2585controller # [7504976.623418] controller data-mesher[230]: time=2026-09-03T20:06:42.675Z level=INFO msg="merging remote state" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt586controller # [7504976.623418] controller data-mesher[230]: time=2026-09-03T20:06:42.676Z level=DEBUG msg="new file detected" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck587controller # [7504976.625356] controller data-mesher[230]: time=2026-09-03T20:06:42.676Z level=INFO msg="state exchange complete" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt timeout=5s588controller # [7504976.625356] controller data-mesher[230]: time=2026-09-03T20:06:42.676Z level=DEBUG msg="push/pull successful" interval=5s589controller # [7504976.632294] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...590controller # [7504976.735727] controller data-mesher[230]: time=2026-09-03T20:06:42.788Z level=INFO msg="download complete" name=dm_wg_star_wg_star/EYubHgwK6l7uZn6VR692j3-CBBOiQOM_6G2_YIHkhck signed_at="2026-09-03 20:06:35.487 +0000 UTC" signed_by="EYubHgwK6l7uZn6VR692j3+CBBOiQOM/6G2/YIHkhck=" peer=12D3KooWLArj3AqHk9brvghuD9LLyzDkq72Pt4kUoWyY3bLwFLP2 written=true elapsed=113.583793ms591controller # [7504976.859903] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.592controller # [7504976.860166] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.593peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8347:d476:2a4b:65b7, in 1.00 seconds)594controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::03e0:aacd:42ac:6269595controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::03e0:aacd:42ac:6269, in 0.01 seconds)596peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::03e0:aacd:42ac:6269597peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::03e0:aacd:42ac:6269, in 0.01 seconds)598peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::35fa:0039:d6ca:cb57599peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::35fa:0039:d6ca:cb57, in 0.01 seconds)600(finished: run the VM test script, in 38.63 seconds)601controller # [7504979.274779] controller data-mesher[230]: time=2026-09-03T20:06:45.327Z level=INFO msg="received state sync from peer" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt602controller # [7504979.274779] controller data-mesher[230]: time=2026-09-03T20:06:45.327Z level=INFO msg="merging remote state" peer=12D3KooWAzrZtYMgQL5qJux2QuuLVaJNEv7Hf1SizQKM7fbzWxvt603peer2 # [7504979.274065] peer2 data-mesher[218]: time=2026-09-03T20:06:45.327Z level=DEBUG msg="attempting push/pull" peer_count=2604peer2 # [7504979.274805] peer2 data-mesher[218]: time=2026-09-03T20:06:45.327Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s605peer2 # [7504979.275869] peer2 data-mesher[218]: time=2026-09-03T20:06:45.329Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466606peer2 # [7504979.277039] peer2 data-mesher[218]: time=2026-09-03T20:06:45.330Z level=INFO msg="state exchange complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s607peer2 # [7504979.277100] peer2 data-mesher[218]: time=2026-09-03T20:06:45.330Z level=DEBUG msg="push/pull successful" interval=5s608test script finished in 42.44s609cleanup610kill NspawnMachine (pid 50)611peer1 # [7504981.623804] peer1 data-mesher[218]: time=2026-09-03T20:06:47.676Z level=DEBUG msg="attempting push/pull" peer_count=1612peer1 # [7504981.624705] peer1 data-mesher[218]: time=2026-09-03T20:06:47.677Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s613peer1 # [7504981.626265] peer1 data-mesher[218]: time=2026-09-03T20:06:47.679Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466614peer1 # [7504981.627412] peer1 data-mesher[218]: time=2026-09-03T20:06:47.680Z level=INFO msg="state exchange complete" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466 timeout=5s615peer1 # [7504981.627483] peer1 data-mesher[218]: time=2026-09-03T20:06:47.680Z level=DEBUG msg="push/pull successful" interval=5s616peer2 # [7504981.625063] peer2 data-mesher[218]: time=2026-09-03T20:06:47.678Z level=INFO msg="received state sync from peer" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466617peer2 # [7504981.625385] peer2 data-mesher[218]: time=2026-09-03T20:06:47.678Z level=INFO msg="merging remote state" peer=12D3KooWFrNBKcZwfMwxD3rbRQ5DeByJLixD9xMKNUzgN7ZiA466618kill NspawnMachine (pid 53)619Container controller terminated by signal KILL.620kill NspawnMachine (pid 779)621Container peer1 terminated by signal KILL.622Container peer2 terminated by signal KILL.623(finished: cleanup, in 0.84 seconds)