container-test-run-dm-wireguard-star
checks.x86_64-linux.dm-wireguard-star
· build #111
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 controller, peer1, peer2,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11controller: systemd-nspawn running (pid 50)12controller: Waiting for journal at /build/vm-state-controller/var/log/journal...13peer1: systemd-nspawn running (pid 53)14peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...15controller: waiting for unit data-mesher.service16nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE17nixos-nspawn(controller): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.18nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE19nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.20Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.21░ Spawning container controller on /build/vm-state-controller.22Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.23░ Spawning container peer1 on /build/vm-state-peer1.24peer1 # [46080.758659] peer1 systemd-journald[96]: Journal started25peer1 # [46080.758700] peer1 systemd-journald[96]: Runtime Journal (/run/log/journal/1a6e676fd80f4e499df054e587524a98) is 8M, max 3.7G, 3.7G free.26peer1 # [46080.761635] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.27controller # [46080.752494] controller systemd-journald[105]: Journal started28controller # [46080.752527] controller systemd-journald[105]: Runtime Journal (/run/log/journal/5d3df20210384b8e846a0c0386811ce3) is 8M, max 3.7G, 3.7G free.29controller # [46080.754462] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.30controller # [46080.760586] controller systemd[1]: Starting Flush Journal to Persistent Storage...31controller # [46080.761017] controller systemd[1]: Starting Network Name Resolution...32controller # [46080.761583] controller systemd[1]: Starting Create Static Device Nodes in /dev...33controller # [46080.766176] controller systemd-journald[105]: Time spent on flushing to /var/log/journal/5d3df20210384b8e846a0c0386811ce3 is 1.050ms for 6 entries.34controller # [46080.766176] controller systemd-journald[105]: System Journal (/var/log/journal/5d3df20210384b8e846a0c0386811ce3) is 8M, max 4G, 3.9G free.35peer1 # [46080.767156] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...36peer1 # [46080.767675] peer1 systemd[1]: Starting Network Name Resolution...37controller # [46080.770234] controller systemd[1]: Finished Create Static Device Nodes in /dev.38peer1 # [46080.768158] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...39controller # [46080.770402] controller systemd[1]: Reached target Preparation for Local File Systems.40peer1 # [46080.771753] peer1 systemd-journald[96]: Time spent on flushing to /var/log/journal/1a6e676fd80f4e499df054e587524a98 is 1.472ms for 6 entries.41controller # [46080.770461] controller systemd[1]: Reached target Local File Systems.42peer1 # [46080.771753] peer1 systemd-journald[96]: System Journal (/var/log/journal/1a6e676fd80f4e499df054e587524a98) is 8M, max 4G, 3.9G free.43controller # [46080.771023] controller systemd[1]: Listening on Boot Loader Control Service Socket.44peer1 # [46080.778106] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.45controller # [46080.771060] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container46peer1 # [46080.778341] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.47controller # [46080.771633] controller systemd[1]: Starting Save Transient machine-id to Disk...48peer1 # [46080.779171] peer1 systemd[1]: Reached target Preparation for Local File Systems.49peer1 # [46080.779228] peer1 systemd[1]: Reached target Local File Systems.50controller # [46080.771653] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys51peer1 # [46080.779718] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.52controller # [46080.771904] controller systemd[1]: Finished Flush Journal to Persistent Storage.53peer1 # [46080.779747] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container54controller # [46080.772889] controller systemd[1]: Starting Create System Files and Directories...55peer1 # [46080.780239] peer1 systemd[1]: Starting Save Transient machine-id to Disk...56controller # [46080.784030] controller systemd-tmpfiles[145]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted57peer1 # [46080.780626] peer1 systemd[1]: Starting Create System Files and Directories...58controller # [46080.784196] controller systemd-tmpfiles[145]: fchmod() of /var/log/journal failed: Operation not permitted59peer1 # [46080.780640] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys60controller # [46080.784301] controller systemd-tmpfiles[145]: fchmod() of /var/log/journal/5d3df20210384b8e846a0c0386811ce3 failed: Operation not permitted61peer1 # [46080.793005] peer1 systemd-tmpfiles[137]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted62controller # [46080.784454] controller systemd-tmpfiles[145]: fchmod() of /run/log/journal failed: Operation not permitted63peer1 # [46080.793197] peer1 systemd-tmpfiles[137]: fchmod() of /var/log/journal failed: Operation not permitted64controller # [46080.785369] controller systemd[1]: Finished Create System Files and Directories.65controller # [46080.785991] controller systemd[1]: Starting Rebuild Journal Catalog...66peer1 # [46080.793313] peer1 systemd-tmpfiles[137]: fchmod() of /var/log/journal/1a6e676fd80f4e499df054e587524a98 failed: Operation not permitted67controller # [46080.786358] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...68controller # [46080.792383] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.69controller # [46080.803248] controller systemd[1]: Finished Rebuild Journal Catalog.70controller # [46080.804275] controller systemd[1]: Starting Update is Completed...71controller # [46080.811266] controller systemd[1]: Finished Update is Completed.72controller # [46080.842940] controller systemd[1]: Finished Save Transient machine-id to Disk.73controller # [46080.852413] controller systemd[1]: Finished Firewall.74controller # [46080.852481] controller systemd[1]: Reached target Preparation for Network.75controller # [46080.852640] controller systemd[1]: Listening on Network Management Resolve Hook Socket.76controller # [46080.853299] controller systemd[1]: Starting Network Management...77controller # [46081.187812] controller systemd-networkd[225]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted78controller # [46081.187896] controller systemd-networkd[225]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted79controller # [46081.194627] controller systemd-networkd[225]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.80controller # [46081.194804] controller systemd-networkd[225]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.81controller # [46081.194898] controller systemd-networkd[225]: lo: Link UP82controller # [46081.194901] controller systemd-networkd[225]: lo: Gained carrier83controller # [46081.195055] controller systemd-networkd[225]: eth1: Configuring with /etc/systemd/network/40-eth1.network.84controller # [46081.195331] controller systemd[1]: Started Network Management.85controller # [46081.208246] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...86controller # [46081.208733] controller systemd-networkd[225]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.87controller # [46081.209032] controller systemd-networkd[225]: wg-star: netdev ready88controller # [46081.209149] controller systemd-networkd[225]: eth1: Link UP89controller # [46081.209279] controller systemd-networkd[225]: eth1: Gained carrier90controller # [46081.221719] controller systemd-networkd[225]: wg-star: Link UP91controller # [46081.221724] controller systemd-networkd[225]: wg-star: Gained carrier92controller # [46081.222874] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.93peer1 # [46080.793498] peer1 systemd-tmpfiles[137]: fchmod() of /run/log/journal failed: Operation not permitted94peer1 # [46080.794847] peer1 systemd[1]: Finished Create System Files and Directories.95peer1 # [46080.795747] peer1 systemd[1]: Starting Rebuild Journal Catalog...96peer1 # [46080.796313] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...97peer1 # [46080.804304] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.98peer1 # [46080.812314] peer1 systemd[1]: Finished Rebuild Journal Catalog.99peer1 # [46080.813049] peer1 systemd[1]: Starting Update is Completed...100peer1 # [46080.817985] peer1 systemd[1]: Finished Update is Completed.101peer1 # [46080.842963] peer1 systemd[1]: Finished Save Transient machine-id to Disk.102peer1 # [46080.874119] peer1 systemd[1]: Finished Firewall.103peer1 # [46080.874275] peer1 systemd[1]: Reached target Preparation for Network.104peer1 # [46080.874460] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.105peer1 # [46080.875211] peer1 systemd[1]: Starting Network Management...106peer1 # [46081.183031] peer1 systemd-networkd[214]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted107peer1 # [46081.183113] peer1 systemd-networkd[214]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted108peer1 # [46081.189673] peer1 systemd-networkd[214]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.109peer1 # [46081.189842] peer1 systemd-networkd[214]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.110peer1 # [46081.189926] peer1 systemd-networkd[214]: lo: Link UP111peer1 # [46081.189931] peer1 systemd-networkd[214]: lo: Gained carrier112peer1 # [46081.190076] peer1 systemd-networkd[214]: eth1: Configuring with /etc/systemd/network/40-eth1.network.113peer1 # [46081.190361] peer1 systemd[1]: Started Network Management.114peer1 # [46081.190768] peer1 systemd-networkd[214]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.115peer1 # [46081.190975] peer1 systemd-networkd[214]: wg-star: netdev ready116peer1 # [46081.191065] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...117peer1 # [46081.191104] peer1 systemd-networkd[214]: eth1: Link UP118peer1 # [46081.191261] peer1 systemd-networkd[214]: eth1: Gained carrier119peer1 # [46081.208755] peer1 systemd-networkd[214]: wg-star: Link UP120peer1 # [46081.208757] peer1 systemd-networkd[214]: wg-star: Gained carrier121peer1 # [46081.213243] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.122peer1 # [46081.311926] peer1 systemd-resolved[119]: Positive Trust Anchors:123peer1 # [46081.311936] peer1 systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d124peer1 # [46081.311939] peer1 systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16125peer1 # [46081.311955] peer1 systemd-resolved[119]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test126peer1 # [46081.322840] peer1 systemd-resolved[119]: Using system hostname 'peer1'.127peer1 # [46081.323968] peer1 systemd[1]: Started Network Name Resolution.128peer1 # [46081.324060] peer1 systemd[1]: Reached target Network.129peer1 # [46081.324109] peer1 systemd[1]: Reached target System Initialization.130peer1 # [46081.324167] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.131peer1 # [46081.324190] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.132peer1 # [46081.324207] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container133peer1 # [46081.324218] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.134peer1 # [46081.324228] peer1 systemd[1]: Reached target Path Units.135peer1 # [46081.324249] peer1 systemd[1]: Reached target Timer Units.136peer1 # [46081.324334] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.137peer1 # [46081.324420] peer1 systemd[1]: Listening on Nix Daemon Socket.138peer1 # [46081.324509] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.139peer1 # [46081.324526] peer1 systemd[1]: Reached target Socket Units.140peer1 # [46081.324553] peer1 systemd[1]: Reached target Basic System.141peer1 # [46081.325376] peer1 systemd[1]: Starting data mesher daemon...142peer1 # [46081.325876] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...143peer1 # [46081.326355] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...144peer1 # [46081.327068] peer1 systemd[1]: Starting D-Bus System Message Bus...145peer1 # [46081.357432] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.146peer1 # [46081.426491] peer1 nsncd[221]: Sep 04 20:04:55.849 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"147peer1 # [46081.426514] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).148peer1 # [46081.426541] peer1 systemd[1]: Reached target Host and Network Name Lookups.149peer1 # [46081.426569] peer1 systemd[1]: Reached target User and Group Name Lookups.150controller # [46081.312054] controller systemd-resolved[128]: Positive Trust Anchors:151peer1 # [46081.438138] peer1 systemd[1]: Starting User Login Management...152controller # [46081.312062] controller systemd-resolved[128]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d153peer1 # [46081.438532] peer1 systemd[1]: Starting Permit User Sessions...154controller # [46081.312067] controller systemd-resolved[128]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16155controller # [46081.312084] controller systemd-resolved[128]: 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 test156controller # [46081.323963] controller systemd-resolved[128]: Using system hostname 'controller'.157controller # [46081.325015] controller systemd[1]: Started Network Name Resolution.158controller # [46081.325071] controller systemd[1]: Reached target Network.159controller # [46081.325107] controller systemd[1]: Reached target System Initialization.160controller # [46081.325157] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.161controller # [46081.325174] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container162controller # [46081.325188] controller systemd[1]: Started Daily Cleanup of Temporary Directories.163controller # [46081.325201] controller systemd[1]: Reached target Path Units.164controller # [46081.325218] controller systemd[1]: Reached target Timer Units.165controller # [46081.325292] controller systemd[1]: Listening on D-Bus System Message Bus Socket.166controller # [46081.325349] controller systemd[1]: Listening on Nix Daemon Socket.167controller # [46081.325414] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.168controller # [46081.325423] controller systemd[1]: Reached target Socket Units.169controller # [46081.325444] controller systemd[1]: Reached target Basic System.170controller # [46081.326172] controller systemd[1]: Starting data mesher daemon...171controller # [46081.326507] controller systemd[1]: Starting Import lastlog data into lastlog2 database...172controller # [46081.326888] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...173controller # [46081.327513] controller systemd[1]: Starting D-Bus System Message Bus...174controller # [46081.359118] controller systemd[1]: Finished Import lastlog data into lastlog2 database.175controller # [46081.417561] controller nsncd[232]: Sep 04 20:04:55.840 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"176controller # [46081.417612] controller systemd[1]: Started Name Service Cache Daemon (nsncd).177controller # [46081.417656] controller systemd[1]: Reached target Host and Network Name Lookups.178controller # [46081.417695] controller systemd[1]: Reached target User and Group Name Lookups.179controller # [46081.418634] controller systemd[1]: Starting User Login Management...180controller # [46081.419125] controller systemd[1]: Starting Permit User Sessions...181controller # [46081.442372] controller systemd[1]: Finished Permit User Sessions.182controller # [46081.443268] controller systemd[1]: Started Console Getty.183controller # [46081.443302] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0184controller # [46081.443315] controller systemd[1]: Reached target Login Prompts.185controller # [46081.490295] controller dbus-broker-launch[233]: Looking up NSS user entry for 'systemd-timesync'...186controller # [46081.490794] controller dbus-broker-launch[233]: NSS returned no entry for 'systemd-timesync'187controller # [46081.490794] controller dbus-broker-launch[233]: Invalid user-name in /nix/store/2vdmzcm94vw773ig833bsswy8c0krm4j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"188controller # [46081.491167] controller systemd[1]: Started D-Bus System Message Bus.189controller # [46081.494932] controller dbus-broker-launch[233]: Ready190controller # [46081.688291] controller data-mesher[230]: time=2026-09-04T20:04:56.111Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]191controller # [46081.688653] controller data-mesher[230]: time=2026-09-04T20:04:56.111Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z: [/dns/controller.clan/tcp/7946]} {12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi: [/dns/peer1.clan/tcp/7946]} {12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z192controller # [46081.688653] controller data-mesher[230]: time=2026-09-04T20:04:56.111Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml193controller # [46081.689478] controller data-mesher[230]: time=2026-09-04T20:04:56.112Z level=INFO msg="checking file integrity"194controller # [46081.689536] controller data-mesher[230]: time=2026-09-04T20:04:56.112Z level=INFO msg="file integrity check complete"195controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="libp2p host created" peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z 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::9053:b839:432e:800a/tcp/7946]"196controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="registered HTTP route" method=GET path=/files197controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name198controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name199controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="starting server"200controller # [46081.693340] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="waiting for DHT to populate" delay=10s201controller # [46081.693395] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="HTTP server listening" address=[::1]:7331202peer1 # [46081.443681] peer1 systemd[1]: Finished Permit User Sessions.203peer1 # [46081.444213] peer1 systemd[1]: Started Console Getty.204peer1 # [46081.444234] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0205peer1 # [46081.444244] peer1 systemd[1]: Reached target Login Prompts.206peer1 # [46081.483808] peer1 dbus-broker-launch[222]: Looking up NSS user entry for 'systemd-timesync'...207peer1 # [46081.484220] peer1 dbus-broker-launch[222]: NSS returned no entry for 'systemd-timesync'208peer1 # [46081.484220] peer1 dbus-broker-launch[222]: Invalid user-name in /nix/store/2vdmzcm94vw773ig833bsswy8c0krm4j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"209peer1 # [46081.484443] peer1 systemd[1]: Started D-Bus System Message Bus.210peer1 # [46081.488059] peer1 dbus-broker-launch[222]: Ready211peer1 # [46081.698566] peer1 data-mesher[219]: time=2026-09-04T20:04:56.121Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]212peer1 # [46081.698846] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z: [/dns/controller.clan/tcp/7946]} {12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi: [/dns/peer1.clan/tcp/7946]} {12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi213peer1 # [46081.698872] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml214peer1 # [46081.699564] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=INFO msg="checking file integrity"215peer1 # [46081.699635] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=INFO msg="file integrity check complete"216peer1 # [46081.702594] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="libp2p host created" peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi 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::11ba:c4d4:8af6:629f/tcp/7946]"217peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="registered HTTP route" method=GET path=/files218peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name219peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name220peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="starting server"221peer1 # [46081.702729] peer1 data-mesher[219]: time=2026-09-04T20:04:56.126Z level=INFO msg="HTTP server listening" address=[::1]:7331222peer1 # [46081.702744] peer1 data-mesher[219]: time=2026-09-04T20:04:56.126Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331223peer1 # [46081.702762] peer1 data-mesher[219]: time=2026-09-04T20:04:56.126Z level=INFO msg="waiting for DHT to populate" delay=10s224peer1 # [46081.705055] peer1 data-mesher[219]: time=2026-09-04T20:04:56.128Z level=INFO msg="peer connected" peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z remote_addr=/ip4/192.168.1.1/tcp/7946225peer1 # [46081.750673] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.226peer1 # [46081.802311] peer1 systemd-logind[239]: New seat seat0.227peer1 # [46081.802456] peer1 systemd[1]: Started User Login Management.228peer1 # [46081.805291] peer1 systemd[1]: Starting linger-users.service...229peer1 # [46081.812275] peer1 systemd[1]: linger-users.service: Deactivated successfully.230peer1 # [46081.812322] peer1 systemd[1]: Finished linger-users.service.231controller # [46081.693434] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331232controller # [46081.705269] controller data-mesher[230]: time=2026-09-04T20:04:56.128Z level=INFO msg="peer connected" peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi remote_addr=/ip4/192.168.1.2/tcp/7946233controller # [46081.745080] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.234controller # [46081.779617] controller systemd-logind[250]: New seat seat0.235controller # [46081.779789] controller systemd[1]: Started User Login Management.236controller # [46081.780695] controller systemd[1]: Starting linger-users.service...237controller # [46081.810591] controller systemd[1]: linger-users.service: Deactivated successfully.238controller # [46081.810708] controller systemd[1]: Finished linger-users.service.239controller # [46082.944104] controller systemd-networkd[225]: eth1: Gained IPv6LL240peer1 # [46083.201073] peer1 systemd-networkd[214]: eth1: Gained IPv6LL241controller: still waiting for container 'controller' to reach ready state...242controller: (finished: waiting for unit data-mesher.service, in 11.64 seconds)243peer1: waiting for unit data-mesher.service244peer1: (finished: waiting for unit data-mesher.service, in 0.01 seconds)245??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.246 File "/nix/store/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39247controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller248??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.249 File "/nix/store/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39250controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.00 seconds)251peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller252peer1 # [46091.694687] peer1 data-mesher[219]: time=2026-09-04T20:05:06.118Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z253peer1 # [46091.694687] peer1 data-mesher[219]: time=2026-09-04T20:05:06.118Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z254peer1 # [46091.702904] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="performing state exchange with peers on join" count=1255peer1 # [46091.702940] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s256peer1 # [46091.703166] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z257peer1 # [46091.703166] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s258peer1 # [46091.703204] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="server started"259peer1 # [46091.703236] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="starting expired-file sweeper" interval=1m0s260peer1 # [46091.703304] peer1 systemd[1]: Started data mesher daemon.261peer1 # [46091.722210] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...262peer1 # [46091.778215] peer1 data-mesher[219]: time=2026-09-04T20:05:06.201Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM status=204263peer1 # [46091.778270] peer1 dm-wg-star-publish[272]: Status: 204 No Content264peer1 # [46091.779298] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...265peer1 # [46091.780079] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.266peer1 # [46091.780177] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.267peer1 # [46091.780475] peer1 systemd[1]: Reached target Multi-User System.268peer1 # [46091.845679] peer1 dm-wg-star-reconfig[288]: No controller data available yet, skipping269peer1 # [46091.846312] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.270peer1 # [46091.846385] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.271peer1 # [46091.846585] peer1 systemd[1]: Startup finished in 11.341s.272controller # [46091.694362] controller data-mesher[230]: time=2026-09-04T20:05:06.117Z level=INFO msg="performing state exchange with peers on join" count=1273controller # [46091.694362] controller data-mesher[230]: time=2026-09-04T20:05:06.117Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s274controller # [46091.694835] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi275controller # [46091.694835] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s276controller # [46091.694835] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="server started"277controller # [46091.694895] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="starting expired-file sweeper" interval=1m0s278controller # [46091.694964] controller systemd[1]: Started data mesher daemon.279controller # [46091.696048] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...280controller # [46091.703092] controller data-mesher[230]: time=2026-09-04T20:05:06.126Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi281controller # [46091.703092] controller data-mesher[230]: time=2026-09-04T20:05:06.126Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi282controller # [46091.738299] controller data-mesher[230]: time=2026-09-04T20:05:06.161Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204283controller # [46091.738573] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...284controller # [46091.738866] controller dm-wg-star-publish[282]: Status: 204 No Content285controller # [46091.740335] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.286controller # [46091.740521] controller systemd[1]: Reached target Multi-User System.287controller # [46091.783823] controller dm-wg-star-reconfig[289]: No peer data available yet, skipping288controller # [46091.784479] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.289controller # [46091.802062] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.290controller # [46091.802228] controller systemd[1]: Startup finished in 11.291s.291peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.02 seconds)292controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q .293controller: (finished: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q ., in 0.00 seconds)294controller: waiting for success: wg show wg-star peers | grep -q .295controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)296peer1: waiting for success: wg show wg-star peers | grep -q .297peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.00 seconds)298peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9053:b839:432e:800a299peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9053:b839:432e:800a, in 0.00 seconds)300controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f301controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f, in 0.00 seconds)302controller: must succeed: wg show wg-star peers | wc -l303controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.00 seconds)304peer2: systemd-nspawn running (pid 730)305peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...306peer2: waiting for unit data-mesher.service307nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE308nixos-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.309Note: 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.310░ Spawning container peer2 on /build/vm-state-peer2.311peer1 # [46096.697568] peer1 data-mesher[219]: time=2026-09-04T20:05:11.120Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z312peer1 # [46096.697568] peer1 data-mesher[219]: time=2026-09-04T20:05:11.120Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z313peer1 # [46096.697905] peer1 data-mesher[219]: time=2026-09-04T20:05:11.120Z level=DEBUG msg="new file detected" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller314peer1 # [46096.697905] peer1 data-mesher[219]: time=2026-09-04T20:05:11.121Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller315peer1 # [46096.697905] peer1 data-mesher[219]: time=2026-09-04T20:05:11.121Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z316peer1 # [46096.698064] peer1 data-mesher[219]: time=2026-09-04T20:05:11.121Z level=INFO msg="received file request" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM317peer1 # [46096.699181] peer1 data-mesher[219]: time=2026-09-04T20:05:11.122Z level=INFO msg="file transfer complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM318peer1 # [46096.700389] peer1 data-mesher[219]: time=2026-09-04T20:05:11.123Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z written=true elapsed=2.68149ms319peer1 # [46096.701481] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...320peer1 # [46096.703783] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=DEBUG msg="attempting push/pull" peer_count=1321peer1 # [46096.703814] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s322peer1 # [46096.704253] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z323peer1 # [46096.704320] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s324peer1 # [46096.704337] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=DEBUG msg="push/pull successful" interval=5s325peer1 # [46096.774543] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.326peer1 # [46096.774598] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.327controller # [46096.697129] controller data-mesher[230]: time=2026-09-04T20:05:11.120Z level=DEBUG msg="attempting push/pull" peer_count=1328controller # [46096.697129] controller data-mesher[230]: time=2026-09-04T20:05:11.120Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s329controller # [46096.697728] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi330controller # [46096.697819] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=DEBUG msg="new file detected" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM331controller # [46096.697840] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s332controller # [46096.697840] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=DEBUG msg="push/pull successful" interval=5s333controller # [46096.697883] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM334controller # [46096.697901] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi335controller # [46096.697974] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="received file request" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller336controller # [46096.699161] controller data-mesher[230]: time=2026-09-04T20:05:11.122Z level=INFO msg="file transfer complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller337controller # [46096.700391] controller data-mesher[230]: time=2026-09-04T20:05:11.123Z level=INFO msg="download complete" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi written=true elapsed=2.498346ms338controller # [46096.701466] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...339controller # [46096.704060] controller data-mesher[230]: time=2026-09-04T20:05:11.127Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi340controller # [46096.704060] controller data-mesher[230]: time=2026-09-04T20:05:11.127Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi341controller # [46096.774237] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.342controller # [46096.774399] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.343peer2 # [46097.451909] peer2 systemd-journald[96]: Journal started344peer2 # [46097.451950] peer2 systemd-journald[96]: Runtime Journal (/run/log/journal/f361ddde36d34d57a9e44206b423dc2d) is 8M, max 3.7G, 3.7G free.345peer2 # [46097.452821] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.346peer2 # [46097.456934] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...347peer2 # [46097.457288] peer2 systemd[1]: Starting Network Name Resolution...348peer2 # [46097.457572] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...349peer2 # [46097.463464] peer2 systemd-journald[96]: Time spent on flushing to /var/log/journal/f361ddde36d34d57a9e44206b423dc2d is 1.160ms for 6 entries.350peer2 # [46097.463464] peer2 systemd-journald[96]: System Journal (/var/log/journal/f361ddde36d34d57a9e44206b423dc2d) is 8M, max 4G, 3.9G free.351peer2 # [46097.464630] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.352peer2 # [46097.464720] peer2 systemd[1]: Reached target Preparation for Local File Systems.353peer2 # [46097.464756] peer2 systemd[1]: Reached target Local File Systems.354peer2 # [46097.465117] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.355peer2 # [46097.465140] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container356peer2 # [46097.465446] peer2 systemd[1]: Starting Save Transient machine-id to Disk...357peer2 # [46097.465462] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys358peer2 # [46097.475213] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.359peer2 # [46097.475840] peer2 systemd[1]: Starting Create System Files and Directories...360peer2 # [46097.501200] peer2 systemd-tmpfiles[145]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted361peer2 # [46097.501342] peer2 systemd-tmpfiles[145]: fchmod() of /var/log/journal failed: Operation not permitted362peer2 # [46097.501444] peer2 systemd-tmpfiles[145]: fchmod() of /var/log/journal/f361ddde36d34d57a9e44206b423dc2d failed: Operation not permitted363peer2 # [46097.501589] peer2 systemd-tmpfiles[145]: fchmod() of /run/log/journal failed: Operation not permitted364peer2 # [46097.502661] peer2 systemd[1]: Finished Create System Files and Directories.365peer2 # [46097.503334] peer2 systemd[1]: Starting Rebuild Journal Catalog...366peer2 # [46097.503665] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...367peer2 # [46097.509597] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.368peer2 # [46097.515732] peer2 systemd[1]: Finished Rebuild Journal Catalog.369peer2 # [46097.516246] peer2 systemd[1]: Starting Update is Completed...370peer2 # [46097.521030] peer2 systemd[1]: Finished Update is Completed.371peer2 # [46097.545740] peer2 systemd[1]: Finished Firewall.372peer2 # [46097.545827] peer2 systemd[1]: Reached target Preparation for Network.373peer2 # [46097.545972] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.374peer2 # [46097.546390] peer2 systemd[1]: Starting Network Management...375peer2 # [46097.840234] peer2 systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted376peer2 # [46097.840366] peer2 systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted377peer2 # [46097.848363] peer2 systemd-networkd[213]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.378peer2 # [46097.848544] peer2 systemd-networkd[213]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.379peer2 # [46097.848719] peer2 systemd-networkd[213]: lo: Link UP380peer2 # [46097.848723] peer2 systemd-networkd[213]: lo: Gained carrier381peer2 # [46097.848925] peer2 systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.382peer2 # [46097.849380] peer2 systemd[1]: Started Network Management.383peer2 # [46097.850092] peer2 systemd-networkd[213]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.384peer2 # [46097.850296] peer2 systemd-networkd[213]: wg-star: netdev ready385peer2 # [46097.850443] peer2 systemd-networkd[213]: eth1: Link UP386peer2 # [46097.850579] peer2 systemd-networkd[213]: eth1: Gained carrier387peer2 # [46097.850621] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...388peer2 # [46097.871973] peer2 systemd-networkd[213]: wg-star: Link UP389peer2 # [46097.871983] peer2 systemd-networkd[213]: wg-star: Gained carrier390peer2 # [46097.881536] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.391peer2 # [46097.975411] peer2 systemd-resolved[118]: Positive Trust Anchors:392peer2 # [46097.975422] peer2 systemd-resolved[118]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d393peer2 # [46097.975427] peer2 systemd-resolved[118]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16394peer2 # [46097.975445] peer2 systemd-resolved[118]: 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 test395peer2 # [46097.987877] peer2 systemd-resolved[118]: Using system hostname 'peer2'.396peer2 # [46097.989231] peer2 systemd[1]: Started Network Name Resolution.397peer2 # [46097.989313] peer2 systemd[1]: Reached target Network.398peer2 # [46097.989365] peer2 systemd[1]: Reached target System Initialization.399peer2 # [46097.989433] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.400peer2 # [46097.989457] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.401peer2 # [46097.989483] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container402peer2 # [46097.989497] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.403peer2 # [46097.989512] peer2 systemd[1]: Reached target Path Units.404peer2 # [46097.989533] peer2 systemd[1]: Reached target Timer Units.405peer2 # [46097.989631] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.406peer2 # [46097.989747] peer2 systemd[1]: Listening on Nix Daemon Socket.407peer2 # [46097.989861] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.408peer2 # [46097.989879] peer2 systemd[1]: Reached target Socket Units.409peer2 # [46097.989909] peer2 systemd[1]: Reached target Basic System.410peer2 # [46097.990940] peer2 systemd[1]: Starting data mesher daemon...411peer2 # [46097.991490] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...412peer2 # [46097.992058] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...413peer2 # [46097.992933] peer2 systemd[1]: Starting D-Bus System Message Bus...414peer2 # [46098.027723] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.415peer2 # [46098.082074] peer2 nsncd[220]: Sep 04 20:05:12.505 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"416peer2 # [46098.082130] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).417peer2 # [46098.082176] peer2 systemd[1]: Reached target Host and Network Name Lookups.418peer2 # [46098.082217] peer2 systemd[1]: Reached target User and Group Name Lookups.419peer2 # [46098.083054] peer2 systemd[1]: Starting User Login Management...420peer2 # [46098.083493] peer2 systemd[1]: Starting Permit User Sessions...421peer2 # [46098.112069] peer2 systemd[1]: Finished Permit User Sessions.422peer2 # [46098.112659] peer2 systemd[1]: Started Console Getty.423peer2 # [46098.112688] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0424peer2 # [46098.112700] peer2 systemd[1]: Reached target Login Prompts.425peer2 # [46098.139616] peer2 dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...426peer2 # [46098.140108] peer2 dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'427peer2 # [46098.140108] peer2 dbus-broker-launch[221]: Invalid user-name in /nix/store/2vdmzcm94vw773ig833bsswy8c0krm4j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"428peer2 # [46098.140417] peer2 systemd[1]: Started D-Bus System Message Bus.429peer2 # [46098.144139] peer2 dbus-broker-launch[221]: Ready430peer2 # [46098.328898] peer2 data-mesher[218]: time=2026-09-04T20:05:12.752Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]431peer2 # [46098.330067] peer2 data-mesher[218]: time=2026-09-04T20:05:12.753Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z: [/dns/controller.clan/tcp/7946]} {12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi: [/dns/peer1.clan/tcp/7946]} {12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr432peer2 # [46098.330099] peer2 data-mesher[218]: time=2026-09-04T20:05:12.753Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml433peer2 # [46098.361254] peer2 data-mesher[218]: time=2026-09-04T20:05:12.784Z level=INFO msg="checking file integrity"434peer2 # [46098.361326] peer2 data-mesher[218]: time=2026-09-04T20:05:12.784Z level=INFO msg="file integrity check complete"435peer2 # [46098.363683] peer2 data-mesher[218]: time=2026-09-04T20:05:12.786Z level=INFO msg="libp2p host created" peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr 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::2e78:e6d2:d649:e9b7/tcp/7946]"436peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="registered HTTP route" method=GET path=/files437peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name438peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name439peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="starting server"440peer2 # [46098.363761] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="waiting for DHT to populate" delay=10s441peer2 # [46098.363793] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="HTTP server listening" address=[::1]:7331442peer2 # [46098.363807] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331443controller # [46098.366219] controller data-mesher[230]: time=2026-09-04T20:05:12.789Z level=INFO msg="peer connected" peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr remote_addr=/ip4/192.168.1.3/tcp/7946444peer1 # [46098.368030] peer1 data-mesher[219]: time=2026-09-04T20:05:12.791Z level=INFO msg="peer connected" peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr remote_addr=/ip4/192.168.1.3/tcp/7946445peer2 # [46098.366016] peer2 data-mesher[218]: time=2026-09-04T20:05:12.789Z level=INFO msg="peer connected" peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z remote_addr=/ip4/192.168.1.1/tcp/7946446peer2 # [46098.367788] peer2 data-mesher[218]: time=2026-09-04T20:05:12.791Z level=INFO msg="peer connected" peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi remote_addr=/ip4/192.168.1.2/tcp/7946447peer2 # [46098.436059] peer2 systemd-logind[238]: New seat seat0.448peer2 # [46098.436246] peer2 systemd[1]: Started User Login Management.449peer2 # [46098.437576] peer2 systemd[1]: Starting linger-users.service...450peer2 # [46098.466420] peer2 systemd[1]: linger-users.service: Deactivated successfully.451peer2 # [46098.466568] peer2 systemd[1]: Finished linger-users.service.452peer2 # [46099.584077] peer2 systemd-networkd[213]: eth1: Gained IPv6LL453peer2 # [46101.698623] peer2 data-mesher[218]: time=2026-09-04T20:05:16.121Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z454peer2 # [46101.698623] peer2 data-mesher[218]: time=2026-09-04T20:05:16.121Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z455peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=DEBUG msg="new file detected" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM456peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=DEBUG msg="new file detected" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller457peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM458peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller459peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z460peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z461peer2 # [46101.702558] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...462peer2 # [46101.759151] peer2 dm-wg-star-reconfig[268]: No controller data available yet, skipping463peer2 # [46101.759729] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.464peer2 # [46101.759851] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.465peer2 # [46101.864886] peer2 data-mesher[218]: time=2026-09-04T20:05:16.288Z level=INFO msg="download complete" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z written=true elapsed=166.094202ms466peer2 # [46101.866097] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...467peer2 # [46101.933235] peer2 data-mesher[218]: time=2026-09-04T20:05:16.356Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z written=true elapsed=234.466885ms468peer1 # [46101.705268] peer1 data-mesher[219]: time=2026-09-04T20:05:16.128Z level=DEBUG msg="attempting push/pull" peer_count=1469peer1 # [46101.705268] peer1 data-mesher[219]: time=2026-09-04T20:05:16.128Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s470peer1 # [46101.705764] peer1 data-mesher[219]: time=2026-09-04T20:05:16.129Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z471peer1 # [46101.705837] peer1 data-mesher[219]: time=2026-09-04T20:05:16.129Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s472peer1 # [46101.705859] peer1 data-mesher[219]: time=2026-09-04T20:05:16.129Z level=DEBUG msg="push/pull successful" interval=5s473controller # [46101.698105] controller data-mesher[230]: time=2026-09-04T20:05:16.121Z level=DEBUG msg="attempting push/pull" peer_count=1474controller # [46101.698105] controller data-mesher[230]: time=2026-09-04T20:05:16.121Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s475controller # [46101.698840] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr476controller # [46101.698840] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s477controller # [46101.698950] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=DEBUG msg="push/pull successful" interval=5s478controller # [46101.699122] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="received file request" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM479controller # [46101.699218] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="received file request" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller480controller # [46101.699935] controller data-mesher[230]: time=2026-09-04T20:05:16.123Z level=INFO msg="file transfer complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM481controller # [46101.699965] controller data-mesher[230]: time=2026-09-04T20:05:16.123Z level=INFO msg="file transfer complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller482controller # [46101.705581] controller data-mesher[230]: time=2026-09-04T20:05:16.128Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi483controller # [46101.705581] controller data-mesher[230]: time=2026-09-04T20:05:16.128Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi484peer2 # [46101.949231] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.485peer2 # [46101.949335] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.486peer1 # [46106.705997] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=DEBUG msg="attempting push/pull" peer_count=1487peer1 # [46106.705997] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s488peer1 # [46106.706486] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z489peer1 # [46106.706571] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s490peer1 # [46106.706600] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=DEBUG msg="push/pull successful" interval=5s491peer2 # [46106.699508] peer2 data-mesher[218]: time=2026-09-04T20:05:21.122Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z492peer2 # [46106.699508] peer2 data-mesher[218]: time=2026-09-04T20:05:21.122Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z493peer2: still waiting for container 'peer2' to reach ready state...494controller # [46106.699048] controller data-mesher[230]: time=2026-09-04T20:05:21.122Z level=DEBUG msg="attempting push/pull" peer_count=1495controller # [46106.699048] controller data-mesher[230]: time=2026-09-04T20:05:21.122Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s496controller # [46106.699770] controller data-mesher[230]: time=2026-09-04T20:05:21.123Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr497controller # [46106.699865] controller data-mesher[230]: time=2026-09-04T20:05:21.123Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s498controller # [46106.699883] controller data-mesher[230]: time=2026-09-04T20:05:21.123Z level=DEBUG msg="push/pull successful" interval=5s499controller # [46106.706268] controller data-mesher[230]: time=2026-09-04T20:05:21.129Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi500controller # [46106.706268] controller data-mesher[230]: time=2026-09-04T20:05:21.129Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi501peer1 # [46108.364976] peer1 data-mesher[219]: time=2026-09-04T20:05:22.788Z level=INFO msg="received state sync from peer" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr502peer1 # [46108.364976] peer1 data-mesher[219]: time=2026-09-04T20:05:22.788Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr503peer2 # [46108.364792] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="performing state exchange with peers on join" count=1504peer2 # [46108.364792] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s505peer2 # [46108.365230] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi506peer2 # [46108.365285] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s507peer2 # [46108.365285] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="server started"508peer2 # [46108.365362] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="starting expired-file sweeper" interval=1m0s509peer2 # [46108.365446] peer2 systemd[1]: Started data mesher daemon.510peer2 # [46108.366219] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...511peer2 # [46108.446037] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...512peer2 # [46108.450467] peer2 data-mesher[218]: time=2026-09-04T20:05:22.873Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU status=204513peer2 # [46108.450572] peer2 dm-wg-star-publish[286]: Status: 204 No Content514peer2 # [46108.452372] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.515peer2 # [46108.474076] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.516peer2 # [46108.474324] peer2 systemd[1]: Reached target Multi-User System.517peer2 # [46108.523072] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.518peer2 # [46108.523203] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.519peer1 # [46111.707250] peer1 data-mesher[219]: time=2026-09-04T20:05:26.130Z level=DEBUG msg="attempting push/pull" peer_count=1520peer1 # [46111.707582] peer1 data-mesher[219]: time=2026-09-04T20:05:26.130Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s521peer1 # [46111.707633] peer1 data-mesher[219]: time=2026-09-04T20:05:26.130Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr522peer1 # [46111.707734] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=DEBUG msg="new file detected" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU523peer1 # [46111.707754] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s524peer1 # [46111.707754] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=DEBUG msg="push/pull successful" interval=5s525peer1 # [46111.707790] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU526peer1 # [46111.707803] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr527peer1 # [46111.724259] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...528peer1 # [46111.780600] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.529peer1 # [46111.780708] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.530peer1 # [46111.815940] peer1 data-mesher[219]: time=2026-09-04T20:05:26.239Z level=INFO msg="download complete" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr written=true elapsed=108.152955ms531peer2 # [46111.701165] peer2 data-mesher[218]: time=2026-09-04T20:05:26.124Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z532peer2 # [46111.701165] peer2 data-mesher[218]: time=2026-09-04T20:05:26.124Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z533peer2 # [46111.701694] peer2 data-mesher[218]: time=2026-09-04T20:05:26.125Z level=INFO msg="received file request" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU534peer2 # [46111.702235] peer2 data-mesher[218]: time=2026-09-04T20:05:26.125Z level=INFO msg="file transfer complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU535peer2 # [46111.707473] peer2 data-mesher[218]: time=2026-09-04T20:05:26.130Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi536peer2 # [46111.707473] peer2 data-mesher[218]: time=2026-09-04T20:05:26.130Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi537peer2 # [46111.707901] peer2 data-mesher[218]: time=2026-09-04T20:05:26.131Z level=INFO msg="received file request" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU538peer2 # [46111.708167] peer2 data-mesher[218]: time=2026-09-04T20:05:26.131Z level=INFO msg="file transfer complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU539controller # [46111.700820] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="attempting push/pull" peer_count=1540controller # [46111.700820] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s541controller # [46111.701374] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr542controller # [46111.701491] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="new file detected" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU543controller # [46111.701507] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s544controller # [46111.701507] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="push/pull successful" interval=5s545controller # [46111.701547] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU546controller # [46111.701567] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr547controller # [46111.704453] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...548controller # [46111.805965] controller data-mesher[230]: time=2026-09-04T20:05:26.229Z level=INFO msg="download complete" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr written=true elapsed=104.403855ms549controller # [46111.832295] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.550controller # [46111.832340] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.551controller # [46113.366121] controller data-mesher[230]: time=2026-09-04T20:05:27.789Z level=INFO msg="received state sync from peer" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr552controller # [46113.366121] controller data-mesher[230]: time=2026-09-04T20:05:27.789Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr553peer2 # [46113.365644] peer2 data-mesher[218]: time=2026-09-04T20:05:27.788Z level=DEBUG msg="attempting push/pull" peer_count=2554peer2 # [46113.365644] peer2 data-mesher[218]: time=2026-09-04T20:05:27.788Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s555peer2 # [46113.366457] peer2 data-mesher[218]: time=2026-09-04T20:05:27.789Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z556peer2 # [46113.366617] peer2 data-mesher[218]: time=2026-09-04T20:05:27.789Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s557peer2 # [46113.366644] peer2 data-mesher[218]: time=2026-09-04T20:05:27.789Z level=DEBUG msg="push/pull successful" interval=5s558peer1 # [46116.708689] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=DEBUG msg="attempting push/pull" peer_count=1559peer1 # [46116.708938] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s560peer1 # [46116.709085] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z561peer1 # [46116.709240] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s562peer1 # [46116.709258] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=DEBUG msg="push/pull successful" interval=5s563peer2 # [46116.702573] peer2 data-mesher[218]: time=2026-09-04T20:05:31.125Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z564peer2 # [46116.702573] peer2 data-mesher[218]: time=2026-09-04T20:05:31.125Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z565peer2: still waiting for container 'peer2' to reach ready state...566controller # [46116.702258] controller data-mesher[230]: time=2026-09-04T20:05:31.125Z level=DEBUG msg="attempting push/pull" peer_count=1567controller # [46116.702258] controller data-mesher[230]: time=2026-09-04T20:05:31.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s568controller # [46116.702823] controller data-mesher[230]: time=2026-09-04T20:05:31.126Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr569controller # [46116.702929] controller data-mesher[230]: time=2026-09-04T20:05:31.126Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s570controller # [46116.702951] controller data-mesher[230]: time=2026-09-04T20:05:31.126Z level=DEBUG msg="push/pull successful" interval=5s571controller # [46116.708888] controller data-mesher[230]: time=2026-09-04T20:05:31.132Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi572controller # [46116.708908] controller data-mesher[230]: time=2026-09-04T20:05:31.132Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi573peer1 # [46118.367134] peer1 data-mesher[219]: time=2026-09-04T20:05:32.790Z level=INFO msg="received state sync from peer" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr574peer1 # [46118.367134] peer1 data-mesher[219]: time=2026-09-04T20:05:32.790Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr575peer2 # [46118.366918] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=DEBUG msg="attempting push/pull" peer_count=2576peer2 # [46118.366918] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s577peer2 # [46118.367368] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi578peer2 # [46118.367462] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s579peer2 # [46118.367483] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=DEBUG msg="push/pull successful" interval=5s580peer2: (finished: waiting for unit data-mesher.service, in 23.15 seconds)581??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.582 File "/nix/store/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39583controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1584??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.585 File "/nix/store/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39586controller: (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)587controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1588controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.01 seconds)589peer2: waiting for success: wg show wg-star peers | grep -q .590peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)591peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9053:b839:432e:800a592peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9053:b839:432e:800a, in 0.00 seconds)593controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7594controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7, in 0.00 seconds)595peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7596peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7, in 0.00 seconds)597peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f598peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f, in 0.00 seconds)599(finished: run the VM test script, in 39.88 seconds)600test script finished in 39.90s601cleanup602kill NspawnMachine (pid 50)603kill NspawnMachine (pid 53)604kill NspawnMachine (pid 730)605Container controller terminated by signal KILL.606peer2 # [46119.980077] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.607peer2 # [46119.980724] peer2 systemd[1]: Finished Save Transient machine-id to Disk.608peer2 # [46119.984044] peer2 systemd[1]: Startup finished in 22.765s.609peer2 # [46120.225375] peer2 systemd-networkd[213]: eth1: Link DOWN610peer2 # [46120.225383] peer2 systemd-networkd[213]: eth1: Lost carrier611peer2 # [46120.246210] peer2 systemd-networkd[213]: eth1: Lost IPv6LL address fe80::fc32:b7ff:fea6:2f65.612Container peer1 terminated by signal KILL.613Container peer2 terminated by signal KILL.614(finished: cleanup, in 0.21 seconds)