container-test-run-dm-wireguard-star
checks.aarch64-linux.dm-wireguard-star
· build #293
· raw
1build01: building 55jl4cwaw55xz76bp2ch0j4rs228sp9w-container-test-run-dm-wireguard-star.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Initializing machine ID from random generator.6Test will time out and terminate in 3600.0 seconds7run the VM test script8additionally exposed symbols:9 controller, peer1, peer2,10 vlan1,11 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_ssh12controller: systemd-nspawn running (pid 50)13controller: Waiting for journal at /build/vm-state-controller/var/log/journal...14peer1: systemd-nspawn running (pid 53)15peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...16controller: waiting for unit data-mesher.service17nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE18nixos-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.19nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE20nixos-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.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.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 controller on /build/vm-state-controller.24░ Spawning container peer1 on /build/vm-state-peer1.25controller # No journal files were found.26controller # No journal boot entry found for the specified boot (+0).27peer1 # No journal files were found.28peer1 # No journal boot entry found for the specified boot (+0).29controller # [520822.870794] controller systemd-journald[108]: Journal started30controller # [520822.870853] controller systemd-journald[108]: Runtime Journal (/run/log/journal/28f2def7e4f3413f8932bce915963241) is 8M, max 2.5G, 2.4G free.31controller # [520822.877856] controller systemd[1]: Finished Apply Kernel Variables.32controller # [520822.889744] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.33controller # [520822.902776] controller systemd[1]: Starting Flush Journal to Persistent Storage...34controller # [520822.903713] controller systemd[1]: Starting Network Name Resolution...35controller # [520822.904396] controller systemd[1]: Starting Create Static Device Nodes in /dev...36controller # [520822.955601] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/28f2def7e4f3413f8932bce915963241 is 2ms for 7 entries.37controller # [520822.955601] controller systemd-journald[108]: System Journal (/var/log/journal/28f2def7e4f3413f8932bce915963241) is 8M, max 4G, 3.9G free.38controller # [520822.969419] controller systemd[1]: Finished Create Static Device Nodes in /dev.39controller # [520822.970009] controller systemd[1]: Reached target Preparation for Local File Systems.40controller # [520822.970137] controller systemd[1]: Reached target Local File Systems.41controller # [520822.970852] controller systemd[1]: Listening on Boot Loader Control Service Socket.42controller # [520822.970901] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container43controller # [520822.971979] controller systemd[1]: Starting Save Transient machine-id to Disk...44controller # [520822.972023] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys45controller # [520823.017415] controller systemd[1]: Finished Flush Journal to Persistent Storage.46controller # [520823.019243] controller systemd[1]: Starting Create System Files and Directories...47controller # [520823.038081] controller systemd-tmpfiles[199]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted48controller # [520823.038282] controller systemd-tmpfiles[199]: fchmod() of /var/log/journal failed: Operation not permitted49controller # [520823.038425] controller systemd-tmpfiles[199]: fchmod() of /var/log/journal/28f2def7e4f3413f8932bce915963241 failed: Operation not permitted50controller # [520823.038629] controller systemd-tmpfiles[199]: fchmod() of /run/log/journal failed: Operation not permitted51controller # [520823.040255] controller systemd[1]: Finished Create System Files and Directories.52controller # [520823.041503] controller systemd[1]: Starting Rebuild Journal Catalog...53controller # [520823.042447] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...54controller # [520823.060398] controller systemd[1]: Finished Firewall.55controller # [520823.060738] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.56controller # [520823.062324] controller systemd[1]: Reached target Preparation for Network.57controller # [520823.062794] controller systemd[1]: Listening on Network Management Resolve Hook Socket.58controller # [520823.064762] controller systemd[1]: Starting Network Management...59controller # [520823.065173] controller systemd[1]: Finished Rebuild Journal Catalog.60controller # [520823.067954] controller systemd[1]: Starting Update is Completed...61controller # [520823.080153] controller systemd[1]: Finished Update is Completed.62controller # [520823.559021] controller systemd[1]: Finished Save Transient machine-id to Disk.63controller # [520823.686653] controller systemd-networkd[224]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted64controller # [520823.686744] controller systemd-networkd[224]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted65peer1 # [520822.870970] peer1 systemd-journald[100]: Journal started66controller # [520823.696337] 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.67peer1 # [520822.871024] peer1 systemd-journald[100]: Runtime Journal (/run/log/journal/d581eb8d100a47c4a0f4307cb1cd3b7a) is 8M, max 2.5G, 2.4G free.68controller # [520823.696499] 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.69peer1 # [520822.887213] peer1 systemd[1]: Finished Apply Kernel Variables.70controller # [520823.696644] controller systemd-networkd[224]: lo: Link UP71peer1 # [520822.901725] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.72peer1 # [520822.948450] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...73peer1 # [520822.949597] peer1 systemd[1]: Starting Network Name Resolution...74peer1 # [520822.950291] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...75peer1 # [520822.960211] peer1 systemd-journald[100]: Time spent on flushing to /var/log/journal/d581eb8d100a47c4a0f4307cb1cd3b7a is 3.259ms for 7 entries.76peer1 # [520822.960211] peer1 systemd-journald[100]: System Journal (/var/log/journal/d581eb8d100a47c4a0f4307cb1cd3b7a) is 8M, max 4G, 3.9G free.77controller # [520823.696649] controller systemd-networkd[224]: lo: Gained carrier78peer1 # [520822.978721] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.79controller # [520823.696841] controller systemd-networkd[224]: eth1: Configuring with /etc/systemd/network/40-eth1.network.80peer1 # [520822.979310] peer1 systemd[1]: Reached target Preparation for Local File Systems.81controller # [520823.697473] controller systemd[1]: Started Network Management.82peer1 # [520822.979434] peer1 systemd[1]: Reached target Local File Systems.83controller # [520823.697753] controller systemd-networkd[224]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.84peer1 # [520822.980164] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.85controller # [520823.698050] controller systemd-networkd[224]: wg-star: netdev ready86peer1 # [520822.980217] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container87controller # [520823.698439] controller systemd-networkd[224]: eth1: Link UP88peer1 # [520822.981091] peer1 systemd[1]: Starting Save Transient machine-id to Disk...89controller # [520823.699109] controller systemd-networkd[224]: eth1: Gained carrier90peer1 # [520822.981126] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys91controller # [520823.699404] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...92peer1 # [520823.018271] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.93controller # [520823.736952] controller systemd-networkd[224]: wg-star: Link UP94peer1 # [520823.020023] peer1 systemd[1]: Starting Create System Files and Directories...95controller # [520823.736958] controller systemd-networkd[224]: wg-star: Gained carrier96peer1 # [520823.038783] peer1 systemd-tmpfiles[192]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted97controller # [520823.745360] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.98peer1 # [520823.038989] peer1 systemd-tmpfiles[192]: fchmod() of /var/log/journal failed: Operation not permitted99peer1 # [520823.039136] peer1 systemd-tmpfiles[192]: fchmod() of /var/log/journal/d581eb8d100a47c4a0f4307cb1cd3b7a failed: Operation not permitted100peer1 # [520823.039348] peer1 systemd-tmpfiles[192]: fchmod() of /run/log/journal failed: Operation not permitted101peer1 # [520823.040959] peer1 systemd[1]: Finished Create System Files and Directories.102peer1 # [520823.042233] peer1 systemd[1]: Starting Rebuild Journal Catalog...103peer1 # [520823.043269] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...104peer1 # [520823.054908] peer1 systemd[1]: Finished Firewall.105peer1 # [520823.059089] peer1 systemd[1]: Reached target Preparation for Network.106peer1 # [520823.059895] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.107peer1 # [520823.061436] peer1 systemd[1]: Starting Network Management...108peer1 # [520823.063901] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.109peer1 # [520823.066488] peer1 systemd[1]: Finished Rebuild Journal Catalog.110peer1 # [520823.067443] peer1 systemd[1]: Starting Update is Completed...111peer1 # [520823.078749] peer1 systemd[1]: Finished Update is Completed.112peer1 # [520823.559534] peer1 systemd[1]: Finished Save Transient machine-id to Disk.113peer1 # [520823.698843] peer1 systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted114peer1 # [520823.698926] peer1 systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted115peer1 # [520823.708488] 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.116peer1 # [520823.708646] 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.117peer1 # [520823.708770] peer1 systemd-networkd[213]: lo: Link UP118peer1 # [520823.708774] peer1 systemd-networkd[213]: lo: Gained carrier119peer1 # [520823.708958] peer1 systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.120peer1 # [520823.709596] peer1 systemd[1]: Started Network Management.121peer1 # [520823.736686] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...122peer1 # [520823.737192] peer1 systemd-networkd[213]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.123peer1 # [520823.737501] peer1 systemd-networkd[213]: wg-star: netdev ready124peer1 # [520823.737871] peer1 systemd-networkd[213]: eth1: Link UP125peer1 # [520823.738070] peer1 systemd-networkd[213]: eth1: Gained carrier126peer1 # [520823.789312] peer1 systemd-networkd[213]: wg-star: Link UP127peer1 # [520823.789317] peer1 systemd-networkd[213]: wg-star: Gained carrier128peer1 # [520823.791126] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.129controller # [520823.865667] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.130controller # [520824.039207] controller systemd-resolved[141]: Positive Trust Anchors:131controller # [520824.039221] controller systemd-resolved[141]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d132controller # [520824.039225] controller systemd-resolved[141]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16133controller # [520824.039260] controller systemd-resolved[141]: 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 test134controller # [520824.039934] controller systemd-resolved[141]: Using system hostname 'controller'.135controller # [520824.041736] controller systemd[1]: Started Network Name Resolution.136controller # [520824.041862] controller systemd[1]: Reached target Network.137controller # [520824.041984] controller systemd[1]: Reached target System Initialization.138controller # [520824.042145] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.139controller # [520824.042190] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container140controller # [520824.042233] controller systemd[1]: Started Daily Cleanup of Temporary Directories.141controller # [520824.042267] controller systemd[1]: Reached target Path Units.142controller # [520824.042335] controller systemd[1]: Reached target Timer Units.143controller # [520824.042550] controller systemd[1]: Listening on D-Bus System Message Bus Socket.144controller # [520824.042751] controller systemd[1]: Listening on Nix Daemon Socket.145controller # [520824.042959] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.146controller # [520824.043007] controller systemd[1]: Reached target Socket Units.147controller # [520824.043097] controller systemd[1]: Reached target Basic System.148controller # [520824.060694] controller systemd[1]: Starting data mesher daemon...149controller # [520824.062242] controller systemd[1]: Starting Import lastlog data into lastlog2 database...150controller # [520824.063670] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...151controller # [520824.065884] controller systemd[1]: Starting D-Bus System Message Bus...152controller # [520824.086335] controller systemd[1]: Finished Import lastlog data into lastlog2 database.153peer1 # [520823.880170] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.154peer1 # [520824.018299] peer1 systemd-resolved[154]: Positive Trust Anchors:155peer1 # [520824.018313] peer1 systemd-resolved[154]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d156peer1 # [520824.018317] peer1 systemd-resolved[154]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16157peer1 # [520824.018351] peer1 systemd-resolved[154]: 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 test158peer1 # [520824.019048] peer1 systemd-resolved[154]: Using system hostname 'peer1'.159peer1 # [520824.020828] peer1 systemd[1]: Started Network Name Resolution.160peer1 # [520824.020956] peer1 systemd[1]: Reached target Network.161peer1 # [520824.021080] peer1 systemd[1]: Reached target System Initialization.162peer1 # [520824.021248] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.163peer1 # [520824.021302] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.164peer1 # [520824.021340] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container165peer1 # [520824.021377] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.166peer1 # [520824.021416] peer1 systemd[1]: Reached target Path Units.167peer1 # [520824.021488] peer1 systemd[1]: Reached target Timer Units.168peer1 # [520824.021708] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.169peer1 # [520824.021902] peer1 systemd[1]: Listening on Nix Daemon Socket.170peer1 # [520824.022113] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.171peer1 # [520824.022158] peer1 systemd[1]: Reached target Socket Units.172peer1 # [520824.022235] peer1 systemd[1]: Reached target Basic System.173peer1 # [520824.024874] peer1 systemd[1]: Starting data mesher daemon...174peer1 # [520824.026378] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...175peer1 # [520824.027967] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...176peer1 # [520824.030444] peer1 systemd[1]: Starting D-Bus System Message Bus...177peer1 # [520824.078907] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.178peer1 # [520824.258461] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).179peer1 # [520824.258558] peer1 systemd[1]: Reached target Host and Network Name Lookups.180peer1 # [520824.258786] peer1 nsncd[225]: Oct 22 10:03:49.230 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"181peer1 # [520824.258667] peer1 systemd[1]: Reached target User and Group Name Lookups.182peer1 # [520824.260900] peer1 systemd[1]: Starting User Login Management...183peer1 # [520824.262595] peer1 systemd[1]: Starting Permit User Sessions...184peer1 # [520824.304532] peer1 systemd[1]: Finished Permit User Sessions.185peer1 # [520824.306232] peer1 systemd[1]: Started Console Getty.186peer1 # [520824.306308] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0187peer1 # [520824.306354] peer1 systemd[1]: Reached target Login Prompts.188peer1 # [520824.447049] peer1 dbus-broker-launch[226]: Looking up NSS user entry for 'systemd-timesync'...189peer1 # [520824.448306] peer1 dbus-broker-launch[226]: NSS returned no entry for 'systemd-timesync'190peer1 # [520824.448306] peer1 dbus-broker-launch[226]: Invalid user-name in /nix/store/1hlm6wrw97n6f1zrk2i87506yap0lq8d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"191peer1 # [520824.449020] peer1 systemd[1]: Started D-Bus System Message Bus.192peer1 # [520824.459362] peer1 dbus-broker-launch[226]: Ready193controller # [520824.260509] controller systemd[1]: Started Name Service Cache Daemon (nsncd).194controller # [520824.260611] controller systemd[1]: Reached target Host and Network Name Lookups.195controller # [520824.260720] controller systemd[1]: Reached target User and Group Name Lookups.196controller # [520824.261121] controller nsncd[235]: Oct 22 10:03:49.232 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"197controller # [520824.263041] controller systemd[1]: Starting User Login Management...198controller # [520824.264572] controller systemd[1]: Starting Permit User Sessions...199controller # [520824.304083] controller systemd[1]: Finished Permit User Sessions.200controller # [520824.305469] controller systemd[1]: Started Console Getty.201controller # [520824.305521] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0202controller # [520824.305544] controller systemd[1]: Reached target Login Prompts.203controller # [520824.398324] controller dbus-broker-launch[236]: Looking up NSS user entry for 'systemd-timesync'...204controller # [520824.399976] controller dbus-broker-launch[236]: NSS returned no entry for 'systemd-timesync'205controller # [520824.399976] controller dbus-broker-launch[236]: Invalid user-name in /nix/store/1hlm6wrw97n6f1zrk2i87506yap0lq8d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"206controller # [520824.400524] controller systemd[1]: Started D-Bus System Message Bus.207controller # [520824.408460] controller dbus-broker-launch[236]: Ready208controller # [520824.764152] controller systemd-networkd[224]: eth1: Gained IPv6LL209controller # [520824.807344] controller data-mesher[233]: time=2026-10-22T10:03:49.779Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]210controller # [520824.808449] controller data-mesher[233]: time=2026-10-22T10:03:49.780Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U: [/dns/controller.clan/tcp/7946]} {12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD: [/dns/peer1.clan/tcp/7946]} {12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U211controller # [520824.808449] controller data-mesher[233]: time=2026-10-22T10:03:49.780Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml212controller # [520824.860338] controller data-mesher[233]: time=2026-10-22T10:03:49.831Z level=INFO msg="checking file integrity"213controller # [520824.860338] controller data-mesher[233]: time=2026-10-22T10:03:49.832Z level=INFO msg="file integrity check complete"214controller # [520824.865031] controller data-mesher[233]: time=2026-10-22T10:03:49.836Z level=INFO msg="libp2p host created" peer_id=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U 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::8712:20e5:2d4a:4d1a/tcp/7946]"215controller # [520824.865081] controller data-mesher[233]: time=2026-10-22T10:03:49.836Z level=INFO msg="registered HTTP route" method=GET path=/files216controller # [520824.865081] controller data-mesher[233]: time=2026-10-22T10:03:49.836Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name217controller # [520824.865081] controller data-mesher[233]: time=2026-10-22T10:03:49.836Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name218controller # [520824.865081] controller data-mesher[233]: time=2026-10-22T10:03:49.836Z level=INFO msg="starting server"219controller # [520824.865209] controller data-mesher[233]: time=2026-10-22T10:03:49.837Z level=INFO msg="waiting for DHT to populate" delay=10s220controller # [520824.865281] controller data-mesher[233]: time=2026-10-22T10:03:49.837Z level=INFO msg="HTTP server listening" address=[::1]:7331221controller # [520824.865323] controller data-mesher[233]: time=2026-10-22T10:03:49.837Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331222controller # [520824.877428] controller data-mesher[233]: time=2026-10-22T10:03:49.849Z level=INFO msg="peer connected" peer_id=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD remote_addr=/ip4/192.168.1.2/tcp/7946223peer1 # [520824.818578] peer1 data-mesher[223]: time=2026-10-22T10:03:49.790Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]224peer1 # [520824.819665] peer1 data-mesher[223]: time=2026-10-22T10:03:49.791Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U: [/dns/controller.clan/tcp/7946]} {12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD: [/dns/peer1.clan/tcp/7946]} {12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD225peer1 # [520824.819665] peer1 data-mesher[223]: time=2026-10-22T10:03:49.791Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml226peer1 # [520824.864442] peer1 data-mesher[223]: time=2026-10-22T10:03:49.836Z level=INFO msg="checking file integrity"227peer1 # [520824.865369] peer1 data-mesher[223]: time=2026-10-22T10:03:49.837Z level=INFO msg="file integrity check complete"228peer1 # [520824.869860] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="libp2p host created" peer_id=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD 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::f0eb:2a47:a51d:96a4/tcp/7946]"229peer1 # [520824.869860] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name230peer1 # [520824.869860] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name231peer1 # [520824.869860] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="registered HTTP route" method=GET path=/files232peer1 # [520824.869860] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="starting server"233peer1 # [520824.870022] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="waiting for DHT to populate" delay=10s234peer1 # [520824.870059] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="HTTP server listening" address=[::1]:7331235peer1 # [520824.870082] peer1 data-mesher[223]: time=2026-10-22T10:03:49.841Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331236peer1 # [520824.875517] peer1 data-mesher[223]: time=2026-10-22T10:03:49.847Z level=INFO msg="peer connected" peer_id=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U remote_addr=/ip4/192.168.1.1/tcp/7946237peer1 # [520824.892197] peer1 systemd-networkd[213]: eth1: Gained IPv6LL238peer1 # [520825.049047] peer1 systemd-logind[243]: New seat seat0.239peer1 # [520825.049560] peer1 systemd[1]: Started User Login Management.240peer1 # [520825.052039] peer1 systemd[1]: Starting linger-users.service...241controller # [520825.073693] controller systemd-logind[253]: New seat seat0.242controller # [520825.074047] controller systemd[1]: Started User Login Management.243controller # [520825.100919] controller systemd[1]: Starting linger-users.service...244controller # [520825.114563] controller systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available245controller # [520825.114608] controller systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available246controller # [520825.114648] controller systemd[1]: linger-users.service: Deactivated successfully.247controller # [520825.114768] controller systemd[1]: Finished linger-users.service.248peer1 # [520825.111786] peer1 systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available249peer1 # [520825.111890] peer1 systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available250peer1 # [520825.111954] peer1 systemd[1]: linger-users.service: Deactivated successfully.251peer1 # [520825.112259] peer1 systemd[1]: Finished linger-users.service.252controller: still waiting for container 'controller' to reach ready state...253peer1 # [520834.866131] peer1 data-mesher[223]: time=2026-10-22T10:03:59.837Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U254peer1 # [520834.866131] peer1 data-mesher[223]: time=2026-10-22T10:03:59.838Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U255peer1 # [520834.887295] peer1 data-mesher[223]: time=2026-10-22T10:03:59.842Z level=INFO msg="performing state exchange with peers on join" count=1256peer1 # [520834.887295] peer1 data-mesher[223]: time=2026-10-22T10:03:59.842Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s257peer1 # [520834.887295] peer1 data-mesher[223]: time=2026-10-22T10:03:59.843Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U258peer1 # [520834.887295] peer1 data-mesher[223]: time=2026-10-22T10:03:59.843Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s259peer1 # [520834.887295] peer1 data-mesher[223]: time=2026-10-22T10:03:59.843Z level=INFO msg="server started"260peer1 # [520834.887295] peer1 data-mesher[223]: time=2026-10-22T10:03:59.843Z level=INFO msg="starting expired-file sweeper" interval=1m0s261peer1 # [520834.872243] peer1 systemd[1]: Started data mesher daemon.262peer1 # [520834.874333] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...263peer1 # [520835.028988] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...264peer1 # [520835.045590] peer1 data-mesher[223]: time=2026-10-22T10:04:00.017Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ status=204265peer1 # [520835.045775] peer1 dm-wg-star-publish[289]: Status: 204 No Content266peer1 # [520835.049044] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-publish.service, ignoring: No data available267peer1 # [520835.049094] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-publish.service, ignoring: No data available268peer1 # [520835.129205] peer1 dm-wg-star-reconfig[315]: No controller data available yet, skipping269peer1 # [520835.049118] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.270controller # [520834.865334] controller data-mesher[233]: time=2026-10-22T10:03:59.837Z level=INFO msg="performing state exchange with peers on join" count=1271controller # [520834.865334] controller data-mesher[233]: time=2026-10-22T10:03:59.837Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s272controller # [520834.887359] controller data-mesher[233]: time=2026-10-22T10:03:59.838Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD273controller # [520834.887359] controller data-mesher[233]: time=2026-10-22T10:03:59.838Z level=INFO msg="state exchange complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s274controller # [520834.887359] controller data-mesher[233]: time=2026-10-22T10:03:59.838Z level=INFO msg="server started"275controller # [520834.887359] controller data-mesher[233]: time=2026-10-22T10:03:59.838Z level=INFO msg="starting expired-file sweeper" interval=1m0s276controller # [520834.887359] controller data-mesher[233]: time=2026-10-22T10:03:59.843Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD277controller # [520834.887359] controller data-mesher[233]: time=2026-10-22T10:03:59.843Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD278controller # [520834.866832] controller systemd[1]: Started data mesher daemon.279controller # [520834.869257] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...280controller # [520834.948442] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...281controller # [520835.002052] controller data-mesher[233]: time=2026-10-22T10:03:59.973Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204282controller # [520835.002358] controller dm-wg-star-publish[298]: Status: 204 No Content283controller # [520835.006714] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.284controller # [520835.007582] controller systemd[1]: Reached target Multi-User System.285controller # [520835.035171] controller dm-wg-star-reconfig[323]: No peer data available yet, skipping286controller # [520835.036222] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available287controller # [520835.036294] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available288controller # [520835.036325] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.289controller # [520835.036519] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.290controller # [520835.036970] controller systemd[1]: Startup finished in 12.609s.291controller: (finished: waiting for unit data-mesher.service, in 13.67 seconds)292peer1: waiting for unit data-mesher.service293peer1: (finished: waiting for unit data-mesher.service, in 0.02 seconds)294??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.295 File "/nix/store/nlf5kyx4kq26bwzskrvq0ilbx40qq78w-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39296controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller297??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.298 File "/nix/store/nlf5kyx4kq26bwzskrvq0ilbx40qq78w-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39299controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.01 seconds)300peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller301peer1 # [520835.049287] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.302peer1 # [520835.049813] peer1 systemd[1]: Reached target Multi-User System.303peer1 # [520835.123386] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available304peer1 # [520835.123403] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available305peer1 # [520835.123427] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.306peer1 # [520835.123511] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.307peer1 # [520835.123915] peer1 systemd[1]: Startup finished in 12.704s.308peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.05 seconds)309controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q .310controller # [520839.869074] controller data-mesher[233]: time=2026-10-22T10:04:04.840Z level=DEBUG msg="attempting push/pull" peer_count=1311controller # [520839.869760] controller data-mesher[233]: time=2026-10-22T10:04:04.840Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s312controller # [520839.870985] controller data-mesher[233]: time=2026-10-22T10:04:04.842Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD313controller # [520839.871075] controller data-mesher[233]: time=2026-10-22T10:04:04.842Z level=INFO msg="received file request" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/controller314controller # [520839.872521] controller data-mesher[233]: time=2026-10-22T10:04:04.844Z level=DEBUG msg="new file detected" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ315controller # [520839.874743] controller data-mesher[233]: time=2026-10-22T10:04:04.846Z level=INFO msg="state exchange complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s316controller # [520839.874808] controller data-mesher[233]: time=2026-10-22T10:04:04.846Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ317controller # [520839.874808] controller data-mesher[233]: time=2026-10-22T10:04:04.846Z level=INFO msg="file transfer complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/controller318controller # [520839.874929] controller data-mesher[233]: time=2026-10-22T10:04:04.846Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ signed_at="2026-10-22 10:03:59.993 +0000 UTC" signed_by="y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ=" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD319controller # [520839.875003] controller data-mesher[233]: time=2026-10-22T10:04:04.846Z level=DEBUG msg="push/pull successful" interval=5s320controller # [520839.875823] controller data-mesher[233]: time=2026-10-22T10:04:04.847Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD321controller # [520839.875823] controller data-mesher[233]: time=2026-10-22T10:04:04.847Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD322controller # [520839.876350] controller data-mesher[233]: time=2026-10-22T10:04:04.848Z level=DEBUG msg="new file detected" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ323peer1 # [520839.869914] peer1 data-mesher[223]: time=2026-10-22T10:04:04.841Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U324peer1 # [520839.869914] peer1 data-mesher[223]: time=2026-10-22T10:04:04.841Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U325peer1 # [520839.870375] peer1 data-mesher[223]: time=2026-10-22T10:04:04.841Z level=DEBUG msg="new file detected" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller326peer1 # [520839.870375] peer1 data-mesher[223]: time=2026-10-22T10:04:04.841Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller327peer1 # [520839.870375] peer1 data-mesher[223]: time=2026-10-22T10:04:04.842Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-22 10:03:59.915 +0000 UTC" signed_by="F2j8HkQzL6YVH4lk3umFggso084Ge7Ek46saQDSDuWI=" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U328peer1 # [520839.872050] peer1 data-mesher[223]: time=2026-10-22T10:04:04.843Z level=DEBUG msg="attempting push/pull" peer_count=1329peer1 # [520839.872179] peer1 data-mesher[223]: time=2026-10-22T10:04:04.843Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s330peer1 # [520839.876864] peer1 data-mesher[223]: time=2026-10-22T10:04:04.848Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U331peer1 # [520839.876944] peer1 data-mesher[223]: time=2026-10-22T10:04:04.848Z level=DEBUG msg="new file detected" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller332peer1 # [520839.876944] peer1 data-mesher[223]: time=2026-10-22T10:04:04.848Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s333peer1 # [520839.876944] peer1 data-mesher[223]: time=2026-10-22T10:04:04.848Z level=INFO msg="received file request" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ334peer1 # [520839.877125] peer1 data-mesher[223]: time=2026-10-22T10:04:04.848Z level=DEBUG msg="push/pull successful" interval=5s335peer1 # [520839.878561] peer1 data-mesher[223]: time=2026-10-22T10:04:04.850Z level=INFO msg="file transfer complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ336peer1 # [520839.881268] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...337controller: (finished: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q ., in 1.03 seconds)338controller: waiting for success: wg show wg-star peers | grep -q .339controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)340peer1: waiting for success: wg show wg-star peers | grep -q .341peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)342peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8712:20e5:2d4a:4d1a343controller # [520840.880519] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...344controller # [520840.894374] controller data-mesher[233]: time=2026-10-22T10:04:05.866Z level=INFO msg="download complete" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ signed_at="2026-10-22 10:03:59.993 +0000 UTC" signed_by="y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ=" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD written=true elapsed=1.019517696s345controller # [520841.012123] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available346peer1 # [520840.021304] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available347peer1 # [520840.021399] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available348peer1 # [520840.021454] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.349peer1 # [520840.021820] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.350peer1 # [520840.883365] peer1 data-mesher[223]: time=2026-10-22T10:04:05.855Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-22 10:03:59.915 +0000 UTC" signed_by="F2j8HkQzL6YVH4lk3umFggso084Ge7Ek46saQDSDuWI=" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U written=true elapsed=1.013115728s351controller # [520841.012190] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available352controller # [520841.012230] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.353controller # [520841.012507] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.354peer1 # [520844.876202] peer1 data-mesher[223]: time=2026-10-22T10:04:09.848Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U355peer1 # [520844.876202] peer1 data-mesher[223]: time=2026-10-22T10:04:09.848Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U356peer1 # [520845.437148] peer1 data-mesher[223]: time=2026-10-22T10:04:09.848Z level=DEBUG msg="attempting push/pull" peer_count=1357peer1 # [520845.437148] peer1 data-mesher[223]: time=2026-10-22T10:04:09.848Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s358peer1 # [520845.437148] peer1 data-mesher[223]: time=2026-10-22T10:04:09.850Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U359peer1 # [520845.437148] peer1 data-mesher[223]: time=2026-10-22T10:04:09.851Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s360peer1 # [520845.437148] peer1 data-mesher[223]: time=2026-10-22T10:04:09.851Z level=DEBUG msg="push/pull successful" interval=5s361controller # [520844.875331] controller data-mesher[233]: time=2026-10-22T10:04:09.847Z level=DEBUG msg="attempting push/pull" peer_count=1362controller # [520845.440982] controller data-mesher[233]: time=2026-10-22T10:04:09.847Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s363peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8712:20e5:2d4a:4d1a, in 3.91 seconds)364controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::f0eb:2a47:a51d:96a4365controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::f0eb:2a47:a51d:96a4, in 0.01 seconds)366controller: must succeed: wg show wg-star peers | wc -l367controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.01 seconds)368peer2: systemd-nspawn running (pid 790)369peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...370peer2: waiting for unit data-mesher.service371nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE372nixos-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.373Note: 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.374░ Spawning container peer2 on /build/vm-state-peer2.375controller # [520845.440982] controller data-mesher[233]: time=2026-10-22T10:04:09.848Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD376controller # [520845.440982] controller data-mesher[233]: time=2026-10-22T10:04:09.849Z level=INFO msg="state exchange complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s377controller # [520845.440982] controller data-mesher[233]: time=2026-10-22T10:04:09.849Z level=DEBUG msg="push/pull successful" interval=5s378controller # [520845.440982] controller data-mesher[233]: time=2026-10-22T10:04:09.849Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD379controller # [520845.440982] controller data-mesher[233]: time=2026-10-22T10:04:09.849Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD380peer2 # No journal files were found.381peer2 # No journal boot entry found for the specified boot (+0).382peer2 # No journal boot entry found for the specified boot (+0).383peer2 # [520847.431764] peer2 systemd-journald[100]: Journal started384peer2 # [520847.431818] peer2 systemd-journald[100]: Runtime Journal (/run/log/journal/a6ec29cec1b04bf889fcf02d6e7c0acd) is 8M, max 2.5G, 2.4G free.385peer2 # [520847.442093] peer2 systemd[1]: Finished Apply Kernel Variables.386peer2 # [520847.454622] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.387peer2 # [520847.468835] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...388peer2 # [520847.469654] peer2 systemd[1]: Starting Network Name Resolution...389peer2 # [520847.470541] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...390peer2 # [520847.478640] peer2 systemd-journald[100]: Time spent on flushing to /var/log/journal/a6ec29cec1b04bf889fcf02d6e7c0acd is 2.068ms for 7 entries.391peer2 # [520847.478640] peer2 systemd-journald[100]: System Journal (/var/log/journal/a6ec29cec1b04bf889fcf02d6e7c0acd) is 8M, max 4G, 3.9G free.392peer2 # [520847.493948] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.393peer2 # [520847.494588] peer2 systemd[1]: Reached target Preparation for Local File Systems.394peer2 # [520847.494689] peer2 systemd[1]: Reached target Local File Systems.395peer2 # [520847.495397] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.396peer2 # [520847.495444] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container397peer2 # [520847.496292] peer2 systemd[1]: Starting Save Transient machine-id to Disk...398peer2 # [520847.496327] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys399peer2 # [520847.580048] peer2 systemd[1]: Finished Firewall.400peer2 # [520847.580213] peer2 systemd[1]: Reached target Preparation for Network.401peer2 # [520847.580436] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.402peer2 # [520847.581433] peer2 systemd[1]: Starting Network Management...403peer2 # [520848.257915] peer2 systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted404peer2 # [520848.258000] peer2 systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted405peer2 # [520848.267881] peer2 systemd-networkd[207]: /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.406peer2 # [520848.268050] peer2 systemd-networkd[207]: /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.407peer2 # [520848.268187] peer2 systemd-networkd[207]: lo: Link UP408peer2 # [520848.268190] peer2 systemd-networkd[207]: lo: Gained carrier409peer2 # [520848.268370] peer2 systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.410peer2 # [520848.269028] peer2 systemd[1]: Started Network Management.411peer2 # [520848.270285] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...412peer2 # [520848.270525] peer2 systemd-networkd[207]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.413peer2 # [520848.270829] peer2 systemd-networkd[207]: wg-star: netdev ready414peer2 # [520848.271216] peer2 systemd-networkd[207]: eth1: Link UP415peer2 # [520848.271413] peer2 systemd-networkd[207]: eth1: Gained carrier416peer2 # [520848.332652] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.417peer2 # [520848.335641] peer2 systemd[1]: Starting Create System Files and Directories...418peer2 # [520848.336669] peer2 systemd-networkd[207]: wg-star: Link UP419peer2 # [520848.336674] peer2 systemd-networkd[207]: wg-star: Gained carrier420peer2 # [520848.342910] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.421peer2 # [520848.354318] peer2 systemd-tmpfiles[212]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted422peer2 # [520848.354520] peer2 systemd-tmpfiles[212]: fchmod() of /var/log/journal failed: Operation not permitted423peer2 # [520848.354660] peer2 systemd-tmpfiles[212]: fchmod() of /var/log/journal/a6ec29cec1b04bf889fcf02d6e7c0acd failed: Operation not permitted424peer2 # [520848.354866] peer2 systemd-tmpfiles[212]: fchmod() of /run/log/journal failed: Operation not permitted425peer2 # [520848.356584] peer2 systemd[1]: Finished Create System Files and Directories.426peer2 # [520848.358022] peer2 systemd[1]: Starting Rebuild Journal Catalog...427peer2 # [520848.358932] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...428peer2 # [520848.374147] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.429peer2 # [520848.382553] peer2 systemd[1]: Finished Rebuild Journal Catalog.430peer2 # [520848.383818] peer2 systemd[1]: Starting Update is Completed...431peer2 # [520848.396865] peer2 systemd[1]: Finished Update is Completed.432peer2 # [520848.682420] peer2 systemd-resolved[139]: Positive Trust Anchors:433peer2 # [520848.682437] peer2 systemd-resolved[139]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d434peer2 # [520848.682442] peer2 systemd-resolved[139]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16435peer2 # [520848.682477] peer2 systemd-resolved[139]: 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 test436peer2 # [520848.683292] peer2 systemd-resolved[139]: Using system hostname 'peer2'.437peer2 # [520848.685024] peer2 systemd[1]: Started Network Name Resolution.438peer2 # [520848.685119] peer2 systemd[1]: Reached target Network.439peer2 # [520848.685177] peer2 systemd[1]: Reached target System Initialization.440peer2 # [520848.685267] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.441peer2 # [520848.685298] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.442peer2 # [520848.685319] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container443peer2 # [520848.685337] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.444peer2 # [520848.685357] peer2 systemd[1]: Reached target Path Units.445peer2 # [520848.685388] peer2 systemd[1]: Reached target Timer Units.446peer2 # [520848.685515] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.447peer2 # [520848.685645] peer2 systemd[1]: Listening on Nix Daemon Socket.448peer2 # [520848.685759] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.449peer2 # [520848.685777] peer2 systemd[1]: Reached target Socket Units.450peer2 # [520848.685813] peer2 systemd[1]: Reached target Basic System.451peer2 # [520848.687176] peer2 systemd[1]: Starting data mesher daemon...452peer2 # [520848.688069] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...453peer2 # [520848.688948] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...454peer2 # [520848.690144] peer2 systemd[1]: Starting D-Bus System Message Bus...455peer2 # [520848.771925] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.456peer2 # [520848.915818] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).457peer2 # [520848.915888] peer2 systemd[1]: Reached target Host and Network Name Lookups.458peer2 # [520848.915998] peer2 nsncd[224]: Oct 22 10:04:13.887 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"459peer2 # [520848.915947] peer2 systemd[1]: Reached target User and Group Name Lookups.460peer2 # [520848.917288] peer2 systemd[1]: Starting User Login Management...461peer2 # [520848.918170] peer2 systemd[1]: Starting Permit User Sessions...462peer2 # [520848.972026] peer2 systemd[1]: Finished Permit User Sessions.463peer2 # [520848.973496] peer2 systemd[1]: Started Console Getty.464peer2 # [520848.973558] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0465peer2 # [520848.973591] peer2 systemd[1]: Reached target Login Prompts.466peer2 # [520849.066597] peer2 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'...467peer2 # [520849.068054] peer2 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync'468peer2 # [520849.068054] peer2 dbus-broker-launch[225]: Invalid user-name in /nix/store/1hlm6wrw97n6f1zrk2i87506yap0lq8d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"469peer2 # [520849.068622] peer2 systemd[1]: Started D-Bus System Message Bus.470peer2 # [520849.077991] peer2 dbus-broker-launch[225]: Ready471peer1 # [520849.879422] peer1 data-mesher[223]: time=2026-10-22T10:04:14.850Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U472controller # [520849.877906] controller data-mesher[233]: time=2026-10-22T10:04:14.849Z level=DEBUG msg="attempting push/pull" peer_count=1473peer2 # [520849.478447] peer2 data-mesher[222]: time=2026-10-22T10:04:14.450Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]474controller # [520850.170455] controller data-mesher[233]: time=2026-10-22T10:04:14.849Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s475peer1 # [520849.879422] peer1 data-mesher[223]: time=2026-10-22T10:04:14.850Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U476controller # [520850.170455] controller data-mesher[233]: time=2026-10-22T10:04:14.851Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD477peer2 # [520849.479528] peer2 data-mesher[222]: time=2026-10-22T10:04:14.451Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U: [/dns/controller.clan/tcp/7946]} {12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD: [/dns/peer1.clan/tcp/7946]} {12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi478peer2 # [520849.479528] peer2 data-mesher[222]: time=2026-10-22T10:04:14.451Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml479peer2 # [520849.697806] peer2 systemd-logind[242]: New seat seat0.480peer2 # [520849.698295] peer2 systemd[1]: Started User Login Management.481peer2 # [520849.700677] peer2 systemd[1]: Starting linger-users.service...482peer2 # [520849.746513] peer2 systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available483peer1 # [520850.170435] peer1 data-mesher[223]: time=2026-10-22T10:04:14.851Z level=DEBUG msg="attempting push/pull" peer_count=1484peer1 # [520850.170435] peer1 data-mesher[223]: time=2026-10-22T10:04:14.851Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s485peer1 # [520850.170435] peer1 data-mesher[223]: time=2026-10-22T10:04:14.853Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U486peer1 # [520850.170435] peer1 data-mesher[223]: time=2026-10-22T10:04:14.853Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s487peer1 # [520850.170435] peer1 data-mesher[223]: time=2026-10-22T10:04:14.853Z level=DEBUG msg="push/pull successful" interval=5s488controller # [520850.170455] controller data-mesher[233]: time=2026-10-22T10:04:14.852Z level=INFO msg="state exchange complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s489controller # [520850.170455] controller data-mesher[233]: time=2026-10-22T10:04:14.852Z level=DEBUG msg="push/pull successful" interval=5s490controller # [520850.170455] controller data-mesher[233]: time=2026-10-22T10:04:14.852Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD491controller # [520850.170455] controller data-mesher[233]: time=2026-10-22T10:04:14.852Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD492controller # [520850.193364] controller data-mesher[233]: time=2026-10-22T10:04:15.165Z level=INFO msg="peer connected" peer_id=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi remote_addr=/ip4/192.168.1.3/tcp/7946493peer2 # [520849.746605] peer2 systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available494peer2 # [520849.746659] peer2 systemd[1]: linger-users.service: Deactivated successfully.495peer2 # [520849.746973] peer2 systemd[1]: Finished linger-users.service.496peer2 # [520849.980166] peer2 systemd-networkd[207]: eth1: Gained IPv6LL497peer2 # [520850.179347] peer2 data-mesher[222]: time=2026-10-22T10:04:15.150Z level=INFO msg="checking file integrity"498peer2 # [520850.179347] peer2 data-mesher[222]: time=2026-10-22T10:04:15.150Z level=INFO msg="file integrity check complete"499peer2 # [520850.185733] peer2 data-mesher[222]: time=2026-10-22T10:04:15.157Z level=INFO msg="libp2p host created" peer_id=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi 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::8222:e7ab:adbe:bdca/tcp/7946]"500peer2 # [520850.185873] peer2 data-mesher[222]: time=2026-10-22T10:04:15.157Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name501peer2 # [520850.185873] peer2 data-mesher[222]: time=2026-10-22T10:04:15.157Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name502peer2 # [520850.185873] peer2 data-mesher[222]: time=2026-10-22T10:04:15.157Z level=INFO msg="registered HTTP route" method=GET path=/files503peer2 # [520850.185873] peer2 data-mesher[222]: time=2026-10-22T10:04:15.157Z level=INFO msg="starting server"504peer2 # [520850.186062] peer2 data-mesher[222]: time=2026-10-22T10:04:15.157Z level=INFO msg="waiting for DHT to populate" delay=10s505peer2 # [520850.186157] peer2 data-mesher[222]: time=2026-10-22T10:04:15.157Z level=INFO msg="HTTP server listening" address=[::1]:7331506peer2 # [520850.186212] peer2 data-mesher[222]: time=2026-10-22T10:04:15.158Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331507peer2 # [520850.192648] peer2 data-mesher[222]: time=2026-10-22T10:04:15.164Z level=INFO msg="peer connected" peer_id=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U remote_addr=/ip4/192.168.1.1/tcp/7946508peer2 # [520850.204715] peer2 data-mesher[222]: time=2026-10-22T10:04:15.176Z level=INFO msg="peer connected" peer_id=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD remote_addr=/ip4/192.168.1.2/tcp/7946509peer1 # [520850.205553] peer1 data-mesher[223]: time=2026-10-22T10:04:15.177Z level=INFO msg="peer connected" peer_id=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi remote_addr=/ip4/192.168.1.3/tcp/7946510peer1 # [520854.881188] peer1 data-mesher[223]: time=2026-10-22T10:04:19.853Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U511peer1 # [520854.881188] peer1 data-mesher[223]: time=2026-10-22T10:04:19.853Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U512peer1 # [520855.431756] peer1 data-mesher[223]: time=2026-10-22T10:04:19.854Z level=DEBUG msg="attempting push/pull" peer_count=1513peer1 # [520855.431756] peer1 data-mesher[223]: time=2026-10-22T10:04:19.854Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s514peer1 # [520855.431756] peer1 data-mesher[223]: time=2026-10-22T10:04:19.856Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U515peer1 # [520855.431756] peer1 data-mesher[223]: time=2026-10-22T10:04:19.856Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s516controller # [520854.884109] controller data-mesher[233]: time=2026-10-22T10:04:19.852Z level=DEBUG msg="attempting push/pull" peer_count=1517peer1 # [520855.431756] peer1 data-mesher[223]: time=2026-10-22T10:04:19.856Z level=DEBUG msg="push/pull successful" interval=5s518controller # [520854.884109] controller data-mesher[233]: time=2026-10-22T10:04:19.852Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s519controller # [520854.884109] controller data-mesher[233]: time=2026-10-22T10:04:19.854Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD520controller # [520854.884109] controller data-mesher[233]: time=2026-10-22T10:04:19.854Z level=INFO msg="state exchange complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s521controller # [520854.884109] controller data-mesher[233]: time=2026-10-22T10:04:19.854Z level=DEBUG msg="push/pull successful" interval=5s522controller # [520854.884109] controller data-mesher[233]: time=2026-10-22T10:04:19.855Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD523controller # [520854.884109] controller data-mesher[233]: time=2026-10-22T10:04:19.855Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD524peer2: still waiting for container 'peer2' to reach ready state...525controller # [520859.883496] controller data-mesher[233]: time=2026-10-22T10:04:24.855Z level=DEBUG msg="attempting push/pull" peer_count=1526controller # [520859.883496] controller data-mesher[233]: time=2026-10-22T10:04:24.855Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s527controller # [520859.885613] controller data-mesher[233]: time=2026-10-22T10:04:24.857Z level=INFO msg="merging remote state" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi528controller # [520859.885613] controller data-mesher[233]: time=2026-10-22T10:04:24.857Z level=INFO msg="state exchange complete" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s529controller # [520859.885750] controller data-mesher[233]: time=2026-10-22T10:04:24.857Z level=INFO msg="received file request" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/controller530controller # [520859.885750] controller data-mesher[233]: time=2026-10-22T10:04:24.857Z level=DEBUG msg="push/pull successful" interval=5s531controller # [520859.885879] controller data-mesher[233]: time=2026-10-22T10:04:24.857Z level=INFO msg="received file request" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ532controller # [520859.888600] controller data-mesher[233]: time=2026-10-22T10:04:24.860Z level=INFO msg="file transfer complete" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/controller533controller # [520859.888754] controller data-mesher[233]: time=2026-10-22T10:04:24.860Z level=INFO msg="file transfer complete" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ534peer2 # [520859.884563] peer2 data-mesher[222]: time=2026-10-22T10:04:24.856Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U535peer2 # [520859.884563] peer2 data-mesher[222]: time=2026-10-22T10:04:24.856Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U536peer2 # [520859.885306] peer2 data-mesher[222]: time=2026-10-22T10:04:24.856Z level=DEBUG msg="new file detected" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller537peer2 # [520859.885306] peer2 data-mesher[222]: time=2026-10-22T10:04:24.856Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller538peer2 # [520859.885306] peer2 data-mesher[222]: time=2026-10-22T10:04:24.856Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-22 10:03:59.915 +0000 UTC" signed_by="F2j8HkQzL6YVH4lk3umFggso084Ge7Ek46saQDSDuWI=" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U539peer2 # [520859.885306] peer2 data-mesher[222]: time=2026-10-22T10:04:24.857Z level=DEBUG msg="new file detected" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ540peer2 # [520859.885306] peer2 data-mesher[222]: time=2026-10-22T10:04:24.857Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ541peer2 # [520859.885558] peer2 data-mesher[222]: time=2026-10-22T10:04:24.857Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ signed_at="2026-10-22 10:03:59.993 +0000 UTC" signed_by="y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ=" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U542peer2 # [520859.887365] peer2 data-mesher[222]: time=2026-10-22T10:04:24.859Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD543peer2 # [520859.887365] peer2 data-mesher[222]: time=2026-10-22T10:04:24.859Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD544peer2 # [520859.887365] peer2 data-mesher[222]: time=2026-10-22T10:04:24.859Z level=DEBUG msg="new file detected" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller545peer2 # [520859.887829] peer2 data-mesher[222]: time=2026-10-22T10:04:24.859Z level=DEBUG msg="new file detected" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ546peer2 # [520859.893950] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...547peer2 # [520860.035174] peer2 dm-wg-star-reconfig[285]: No controller data available yet, skipping548peer2 # [520860.036323] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available549peer2 # [520860.036379] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available550peer2 # [520860.036408] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.551peer2 # [520860.036690] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.552peer2 # [520860.186333] peer2 data-mesher[222]: time=2026-10-22T10:04:25.158Z level=INFO msg="performing state exchange with peers on join" count=1553peer2 # [520860.186333] peer2 data-mesher[222]: time=2026-10-22T10:04:25.158Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s554peer1 # [520859.885598] peer1 data-mesher[223]: time=2026-10-22T10:04:24.857Z level=DEBUG msg="attempting push/pull" peer_count=1555peer1 # [520859.886508] peer1 data-mesher[223]: time=2026-10-22T10:04:24.858Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s556peer1 # [520859.888191] peer1 data-mesher[223]: time=2026-10-22T10:04:24.860Z level=INFO msg="merging remote state" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi557peer1 # [520859.888191] peer1 data-mesher[223]: time=2026-10-22T10:04:24.860Z level=INFO msg="state exchange complete" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s558peer1 # [520859.888658] peer1 data-mesher[223]: time=2026-10-22T10:04:24.860Z level=DEBUG msg="push/pull successful" interval=5s559peer2 # [520860.187526] peer2 data-mesher[222]: time=2026-10-22T10:04:25.159Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U560peer2 # [520860.187600] peer2 data-mesher[222]: time=2026-10-22T10:04:25.159Z level=DEBUG msg="new file detected" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller561peer2 # [520860.188196] peer2 data-mesher[222]: time=2026-10-22T10:04:25.160Z level=DEBUG msg="new file detected" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ562peer2 # [520860.188254] peer2 data-mesher[222]: time=2026-10-22T10:04:25.160Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s563peer2 # [520860.188299] peer2 data-mesher[222]: time=2026-10-22T10:04:25.160Z level=INFO msg="server started"564peer2 # [520860.188423] peer2 data-mesher[222]: time=2026-10-22T10:04:25.160Z level=INFO msg="starting expired-file sweeper" interval=1m0s565peer2 # [520860.188557] peer2 systemd[1]: Started data mesher daemon.566peer2 # [520860.190387] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...567peer2 # [520860.575855] peer2 data-mesher[222]: time=2026-10-22T10:04:25.547Z level=INFO msg="download complete" name=dm_wg_star_wg_star/y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ signed_at="2026-10-22 10:03:59.993 +0000 UTC" signed_by="y5qlneFGPQqtE4a051EX24M7ZQP7WkbbMAzl85qepuQ=" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U written=true elapsed=690.413092ms568controller # [520860.187092] controller data-mesher[233]: time=2026-10-22T10:04:25.158Z level=INFO msg="received state sync from peer" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi569controller # [520860.187280] controller data-mesher[233]: time=2026-10-22T10:04:25.158Z level=INFO msg="merging remote state" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi570peer2 # [520860.579983] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...571peer2 # [520860.582794] peer2 data-mesher[222]: time=2026-10-22T10:04:25.554Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-22 10:03:59.915 +0000 UTC" signed_by="F2j8HkQzL6YVH4lk3umFggso084Ge7Ek46saQDSDuWI=" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U written=true elapsed=697.930915ms572peer2 # [520860.589396] peer2 data-mesher[222]: time=2026-10-22T10:04:25.561Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU status=204573peer2 # [520860.589790] peer2 dm-wg-star-publish[292]: Status: 204 No Content574peer2 # [520860.596299] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-publish.service, ignoring: No data available575peer2 # [520860.596401] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-publish.service, ignoring: No data available576peer2 # [520860.596459] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.577peer2 # [520860.624234] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.578peer2 # [520860.625477] peer2 systemd[1]: Reached target Multi-User System.579peer2 # [520860.744046] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available580peer2 # [520860.744149] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available581peer2 # [520860.744202] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.582peer2 # [520860.744494] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.583controller # [520864.886638] controller data-mesher[233]: time=2026-10-22T10:04:29.858Z level=DEBUG msg="attempting push/pull" peer_count=1584controller # [520864.887019] controller data-mesher[233]: time=2026-10-22T10:04:29.858Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s585controller # [520864.888498] controller data-mesher[233]: time=2026-10-22T10:04:29.860Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD586controller # [520864.888652] controller data-mesher[233]: time=2026-10-22T10:04:29.860Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD587controller # [520864.888689] controller data-mesher[233]: time=2026-10-22T10:04:29.860Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD588controller # [520864.889120] controller data-mesher[233]: time=2026-10-22T10:04:29.860Z level=INFO msg="state exchange complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD timeout=5s589controller # [520864.889180] controller data-mesher[233]: time=2026-10-22T10:04:29.861Z level=DEBUG msg="push/pull successful" interval=5s590peer1 # [520864.887389] peer1 data-mesher[223]: time=2026-10-22T10:04:29.859Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U591peer1 # [520864.887389] peer1 data-mesher[223]: time=2026-10-22T10:04:29.859Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U592peer1 # [520864.888292] peer1 data-mesher[223]: time=2026-10-22T10:04:29.860Z level=DEBUG msg="attempting push/pull" peer_count=1593peer1 # [520864.888292] peer1 data-mesher[223]: time=2026-10-22T10:04:29.860Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s594peer1 # [520864.889230] peer1 data-mesher[223]: time=2026-10-22T10:04:29.861Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U595peer1 # [520864.889556] peer1 data-mesher[223]: time=2026-10-22T10:04:29.861Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s596peer1 # [520864.889642] peer1 data-mesher[223]: time=2026-10-22T10:04:29.861Z level=DEBUG msg="push/pull successful" interval=5s597controller # [520865.190632] controller data-mesher[233]: time=2026-10-22T10:04:30.162Z level=INFO msg="received state sync from peer" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi598controller # [520865.190632] controller data-mesher[233]: time=2026-10-22T10:04:30.162Z level=INFO msg="merging remote state" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi599controller # [520865.191249] controller data-mesher[233]: time=2026-10-22T10:04:30.163Z level=DEBUG msg="new file detected" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU600controller # [520865.191383] controller data-mesher[233]: time=2026-10-22T10:04:30.163Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU601controller # [520865.191497] controller data-mesher[233]: time=2026-10-22T10:04:30.163Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU signed_at="2026-10-22 10:04:25.337 +0000 UTC" signed_by="5AZV1fWV1BhbBEMN/fb3sqpaXpySbA7OwM7jSc3bZvU=" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi602controller # [520865.198422] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...603controller # [520865.364909] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available604controller # [520865.365002] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available605peer2 # [520865.189548] peer2 data-mesher[222]: time=2026-10-22T10:04:30.161Z level=DEBUG msg="attempting push/pull" peer_count=2606peer2 # [520865.189957] peer2 data-mesher[222]: time=2026-10-22T10:04:30.161Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s607peer2 # [520865.191778] peer2 data-mesher[222]: time=2026-10-22T10:04:30.163Z level=INFO msg="received file request" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU608peer2 # [520865.192182] peer2 data-mesher[222]: time=2026-10-22T10:04:30.164Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U609peer2 # [520865.193364] peer2 data-mesher[222]: time=2026-10-22T10:04:30.165Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s610peer2 # [520865.193364] peer2 data-mesher[222]: time=2026-10-22T10:04:30.165Z level=INFO msg="file transfer complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU611peer2 # [520865.193540] peer2 data-mesher[222]: time=2026-10-22T10:04:30.165Z level=DEBUG msg="push/pull successful" interval=5s612peer2: still waiting for container 'peer2' to reach ready state...613controller # [520865.471242] controller data-mesher[233]: time=2026-10-22T10:04:30.442Z level=INFO msg="download complete" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU signed_at="2026-10-22 10:04:25.337 +0000 UTC" signed_by="5AZV1fWV1BhbBEMN/fb3sqpaXpySbA7OwM7jSc3bZvU=" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi written=true elapsed=279.525803ms614controller # [520865.365054] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.615controller # [520865.365427] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.616controller # [520869.889763] controller data-mesher[233]: time=2026-10-22T10:04:34.861Z level=DEBUG msg="attempting push/pull" peer_count=1617controller # [520869.889763] controller data-mesher[233]: time=2026-10-22T10:04:34.861Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s618controller # [520869.892441] controller data-mesher[233]: time=2026-10-22T10:04:34.864Z level=INFO msg="merging remote state" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi619controller # [520869.893560] controller data-mesher[233]: time=2026-10-22T10:04:34.865Z level=INFO msg="state exchange complete" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s620controller # [520869.893619] controller data-mesher[233]: time=2026-10-22T10:04:34.865Z level=DEBUG msg="push/pull successful" interval=5s621peer2 # [520869.892030] peer2 data-mesher[222]: time=2026-10-22T10:04:34.862Z level=INFO msg="received state sync from peer" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U622peer2 # [520869.892030] peer2 data-mesher[222]: time=2026-10-22T10:04:34.862Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U623peer2 # [520869.892030] peer2 data-mesher[222]: time=2026-10-22T10:04:34.862Z level=INFO msg="received state sync from peer" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD624peer2 # [520869.892030] peer2 data-mesher[222]: time=2026-10-22T10:04:34.862Z level=INFO msg="merging remote state" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD625peer2 # [520869.892492] peer2 data-mesher[222]: time=2026-10-22T10:04:34.864Z level=INFO msg="received file request" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU626peer2 # [520869.893048] peer2 data-mesher[222]: time=2026-10-22T10:04:34.864Z level=INFO msg="file transfer complete" peer=12D3KooWPX9hfm5iSMH63NZrJaoEmD76PgfnqrWzYw8Aoov4LhRD network="OgZCnBKVQIUVi/FyL8oaj+CP2/AoN9uG1KcfzHycdzA=" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU627peer1 # [520869.890715] peer1 data-mesher[223]: time=2026-10-22T10:04:34.862Z level=DEBUG msg="attempting push/pull" peer_count=1628peer1 # [520869.890715] peer1 data-mesher[223]: time=2026-10-22T10:04:34.862Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s629peer1 # [520869.891373] peer1 data-mesher[223]: time=2026-10-22T10:04:34.863Z level=INFO msg="merging remote state" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi630peer1 # [520869.891588] peer1 data-mesher[223]: time=2026-10-22T10:04:34.863Z level=DEBUG msg="new file detected" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU631peer1 # [520869.891818] peer1 data-mesher[223]: time=2026-10-22T10:04:34.863Z level=INFO msg="state exchange complete" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi timeout=5s632peer1 # [520869.891847] peer1 data-mesher[223]: time=2026-10-22T10:04:34.863Z level=DEBUG msg="push/pull successful" interval=5s633peer1 # [520869.891918] peer1 data-mesher[223]: time=2026-10-22T10:04:34.863Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU634peer1 # [520869.891959] peer1 data-mesher[223]: time=2026-10-22T10:04:34.863Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU signed_at="2026-10-22 10:04:25.337 +0000 UTC" signed_by="5AZV1fWV1BhbBEMN/fb3sqpaXpySbA7OwM7jSc3bZvU=" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi635peer1 # [520869.896427] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...636peer1 # [520869.907039] peer1 data-mesher[223]: time=2026-10-22T10:04:34.878Z level=INFO msg="download complete" name=dm_wg_star_wg_star/5AZV1fWV1BhbBEMN_fb3sqpaXpySbA7OwM7jSc3bZvU signed_at="2026-10-22 10:04:25.337 +0000 UTC" signed_by="5AZV1fWV1BhbBEMN/fb3sqpaXpySbA7OwM7jSc3bZvU=" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi written=true elapsed=15.078768ms637peer1 # [520870.072836] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available638peer1 # [520870.072889] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Failed to read pids.max for control group /system.slice/dm-wg-star-wg-star-reconfig.service, ignoring: No data available639peer1 # [520870.072922] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.640peer1 # [520870.073204] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.641controller # [520870.194614] controller data-mesher[233]: time=2026-10-22T10:04:35.166Z level=INFO msg="received state sync from peer" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi642controller # [520870.194614] controller data-mesher[233]: time=2026-10-22T10:04:35.166Z level=INFO msg="merging remote state" peer=12D3KooWRAUj7SHrqHAHtkWMQksG5qJmKy6WuADGoxCyUvFeC5bi643peer2 # [520870.194071] peer2 data-mesher[222]: time=2026-10-22T10:04:35.165Z level=DEBUG msg="attempting push/pull" peer_count=2644peer2 # [520870.194071] peer2 data-mesher[222]: time=2026-10-22T10:04:35.165Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s645peer2 # [520870.196272] peer2 data-mesher[222]: time=2026-10-22T10:04:35.168Z level=INFO msg="merging remote state" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U646peer2 # [520870.197421] peer2 data-mesher[222]: time=2026-10-22T10:04:35.169Z level=INFO msg="state exchange complete" peer=12D3KooWSsjhLNLz4nsw21mJJ9tcybxibcbUUk175vcoV44Xnb5U timeout=5s647peer2 # [520870.197492] peer2 data-mesher[222]: time=2026-10-22T10:04:35.169Z level=DEBUG msg="push/pull successful" interval=5s648peer2: (finished: waiting for unit data-mesher.service, in 28.68 seconds)649??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.650 File "/nix/store/nlf5kyx4kq26bwzskrvq0ilbx40qq78w-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39651controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1652??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.653 File "/nix/store/nlf5kyx4kq26bwzskrvq0ilbx40qq78w-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39654controller: (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 0.01 seconds)655controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1656controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.01 seconds)657peer2: waiting for success: wg show wg-star peers | grep -q .658peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)659peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8712:20e5:2d4a:4d1a660peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8712:20e5:2d4a:4d1a, in 0.01 seconds)661controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8222:e7ab:adbe:bdca662controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8222:e7ab:adbe:bdca, in 0.01 seconds)663peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8222:e7ab:adbe:bdca664peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8222:e7ab:adbe:bdca, in 0.01 seconds)665peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::f0eb:2a47:a51d:96a4666peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::f0eb:2a47:a51d:96a4, in 0.01 seconds)667(finished: run the VM test script, in 52.47 seconds)668test script finished in 52.59s669cleanup670kill NspawnMachine (pid 50)671peer2 # [520874.046388] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.672peer2 # [520874.048738] peer2 systemd[1]: Finished Save Transient machine-id to Disk.673peer2 # [520874.049326] peer2 systemd[1]: Startup finished in 27.137s.674kill NspawnMachine (pid 53)675Container controller terminated by signal KILL.676kill NspawnMachine (pid 790)677Container peer1 terminated by signal KILL.678Container peer2 terminated by signal KILL.679(finished: cleanup, in 0.64 seconds)