these 24 derivations will be built:
  /nix/store/qcgq2da66g70mh6nvhpd74ql02bj6vg8-system-path.drv
  /nix/store/729h38zm4ssarxd5xm8nr8g12mb6ks1l-dbus-1.drv
  /nix/store/4xdym0kc671iscfzxspwg327mhl8s966-X-Restart-Triggers-dbus-broker.drv
  /nix/store/0fkmnsrf9nz6mkwmagwr5gr3832wrfbi-unit-dbus-broker.service.drv
  /nix/store/pc75b11jhjavkl5998sqnx10h8l8gl39-unit-dbus-broker.service.drv
  /nix/store/3r8ijrf12gxbz411p2ams0kysc6mr27r-user-units.drv
  /nix/store/fpdgvjvbk7rgv4krvywnfjc541bcyjxf-system-units.drv
  /nix/store/7mmzmghgc4nznqxxfs6zc98kzb9k3vca-etc.drv
  /nix/store/niv3g76ccpxc0sv6drcifn3wfzavxldp-activate.drv
  /nix/store/wx6yxjhkmbwfm6n0j2r9blxwd23dfphr-nixos-system-peer2-test.drv
  /nix/store/0zvgcq9b5qw82r9p9b91acza7rbfx0r5-run-peer2-nspawn.drv
  /nix/store/3bpl6v2bsmad3lfxwm69dnrsp3zlpi4d-system-units.drv
  /nix/store/1k07y8b33lisiwqd7mp402drn6mgfnj2-etc.drv
  /nix/store/jp8pf2wh4m98ahbkaqv59an30df5rky2-system-units.drv
  /nix/store/dbj62b1wdv5lsn1hz63k2vm7fjyrx8kj-etc.drv
  /nix/store/imfvxqbsl1dfkn9x9kv7z8wyy9b9a073-activate.drv
  /nix/store/gfbz6136pra84v9sc82c1vzkgs1zv38v-nixos-system-peer1-test.drv
  /nix/store/7m3drjj0nz37zd1srcwh0hhvmn41778d-run-peer1-nspawn.drv
  /nix/store/jdf3j7nkya8lfsa4azjdigh80as3qsmg-activate.drv
  /nix/store/b4ashg4wam428rvx8pzbxfg47sd7cni6-nixos-system-controller-test.drv
  /nix/store/x6s0bfzm7fzhymd2vfmdkj3qyx8l36ji-run-controller-nspawn.drv
  /nix/store/xf1plj6zg88cz945flfji72jhr7hm0cq-driverConfiguration.json.drv
  /nix/store/sfg58y7qgkxm907miiw34mjk82rzk4gd-nixos-test-driver-dm-wireguard-star.drv
  /nix/store/v8hv64gjhbxhxr12w5shhj3cbl8i1fds-container-test-run-dm-wireguard-star.drv
building '/nix/store/qcgq2da66g70mh6nvhpd74ql02bj6vg8-system-path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/qcgq2da66g70mh6nvhpd74ql02bj6vg8-system-path.drv'
system-path> structuredAttrs is enabled
system-path> created 1726 symlinks in user environment
building '/nix/store/729h38zm4ssarxd5xm8nr8g12mb6ks1l-dbus-1.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/729h38zm4ssarxd5xm8nr8g12mb6ks1l-dbus-1.drv'
building '/nix/store/4xdym0kc671iscfzxspwg327mhl8s966-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/4xdym0kc671iscfzxspwg327mhl8s966-X-Restart-Triggers-dbus-broker.drv'
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
building '/nix/store/0fkmnsrf9nz6mkwmagwr5gr3832wrfbi-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/pc75b11jhjavkl5998sqnx10h8l8gl39-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/pc75b11jhjavkl5998sqnx10h8l8gl39-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/3r8ijrf12gxbz411p2ams0kysc6mr27r-user-units.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/0fkmnsrf9nz6mkwmagwr5gr3832wrfbi-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/3r8ijrf12gxbz411p2ams0kysc6mr27r-user-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/3bpl6v2bsmad3lfxwm69dnrsp3zlpi4d-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/fpdgvjvbk7rgv4krvywnfjc541bcyjxf-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jp8pf2wh4m98ahbkaqv59an30df5rky2-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jp8pf2wh4m98ahbkaqv59an30df5rky2-system-units.drv'
building '/nix/store/fpdgvjvbk7rgv4krvywnfjc541bcyjxf-system-units.drv'
building '/nix/store/3bpl6v2bsmad3lfxwm69dnrsp3zlpi4d-system-units.drv'
building '/nix/store/7mmzmghgc4nznqxxfs6zc98kzb9k3vca-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/dbj62b1wdv5lsn1hz63k2vm7fjyrx8kj-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/1k07y8b33lisiwqd7mp402drn6mgfnj2-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/7mmzmghgc4nznqxxfs6zc98kzb9k3vca-etc.drv'
building '/nix/store/1k07y8b33lisiwqd7mp402drn6mgfnj2-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/dbj62b1wdv5lsn1hz63k2vm7fjyrx8kj-etc.drv'
building '/nix/store/jdf3j7nkya8lfsa4azjdigh80as3qsmg-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/niv3g76ccpxc0sv6drcifn3wfzavxldp-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/imfvxqbsl1dfkn9x9kv7z8wyy9b9a073-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jdf3j7nkya8lfsa4azjdigh80as3qsmg-activate.drv'
activate> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/niv3g76ccpxc0sv6drcifn3wfzavxldp-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/b4ashg4wam428rvx8pzbxfg47sd7cni6-nixos-system-controller-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/wx6yxjhkmbwfm6n0j2r9blxwd23dfphr-nixos-system-peer2-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/imfvxqbsl1dfkn9x9kv7z8wyy9b9a073-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/gfbz6136pra84v9sc82c1vzkgs1zv38v-nixos-system-peer1-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/b4ashg4wam428rvx8pzbxfg47sd7cni6-nixos-system-controller-test.drv'
nixos-system-controller-test> structuredAttrs is enabled
building '/nix/store/gfbz6136pra84v9sc82c1vzkgs1zv38v-nixos-system-peer1-test.drv'
nixos-system-peer1-test> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/7m3drjj0nz37zd1srcwh0hhvmn41778d-run-peer1-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/x6s0bfzm7fzhymd2vfmdkj3qyx8l36ji-run-controller-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/wx6yxjhkmbwfm6n0j2r9blxwd23dfphr-nixos-system-peer2-test.drv'
nixos-system-peer2-test> structuredAttrs is enabled
building '/nix/store/0zvgcq9b5qw82r9p9b91acza7rbfx0r5-run-peer2-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/7m3drjj0nz37zd1srcwh0hhvmn41778d-run-peer1-nspawn.drv'
building '/nix/store/x6s0bfzm7fzhymd2vfmdkj3qyx8l36ji-run-controller-nspawn.drv'
building '/nix/store/0zvgcq9b5qw82r9p9b91acza7rbfx0r5-run-peer2-nspawn.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/xf1plj6zg88cz945flfji72jhr7hm0cq-driverConfiguration.json.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/xf1plj6zg88cz945flfji72jhr7hm0cq-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/sfg58y7qgkxm907miiw34mjk82rzk4gd-nixos-test-driver-dm-wireguard-star.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/sfg58y7qgkxm907miiw34mjk82rzk4gd-nixos-test-driver-dm-wireguard-star.drv'
nixos-test-driver-dm-wireguard-star> Running type check (enable/disable: config.skipTypeCheck)
nixos-test-driver-dm-wireguard-star> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
nixos-test-driver-dm-wireguard-star> All checks passed!
nixos-test-driver-dm-wireguard-star> Linting test script (enable/disable: config.skipLint)
nixos-test-driver-dm-wireguard-star> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
nixos-test-driver-dm-wireguard-star> All checks passed!
building '/nix/store/v8hv64gjhbxhxr12w5shhj3cbl8i1fds-container-test-run-dm-wireguard-star.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/v8hv64gjhbxhxr12w5shhj3cbl8i1fds-container-test-run-dm-wireguard-star.drv'
container-test-run-dm-wireguard-star> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-dm-wireguard-star> start all VLans
container-test-run-dm-wireguard-star> (finished: start all VLans, in 0.00 seconds)
container-test-run-dm-wireguard-star> 
container-test-run-dm-wireguard-star> Test will time out and terminate in 3600.0 seconds
container-test-run-dm-wireguard-star> run the VM test script
container-test-run-dm-wireguard-star> additionally exposed symbols:
container-test-run-dm-wireguard-star>     controller, peer1, peer2,
container-test-run-dm-wireguard-star>     vlan1,
container-test-run-dm-wireguard-star>     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_ssh
container-test-run-dm-wireguard-star> controller: systemd-nspawn running (pid 50)
container-test-run-dm-wireguard-star> controller: Waiting for journal at /build/vm-state-controller/var/log/journal...
container-test-run-dm-wireguard-star> peer1: systemd-nspawn running (pid 53)
container-test-run-dm-wireguard-star> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...
container-test-run-dm-wireguard-star> controller: waiting for unit data-mesher.service
container-test-run-dm-wireguard-star> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-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.
container-test-run-dm-wireguard-star> nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-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.
container-test-run-dm-wireguard-star> Note: 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.
container-test-run-dm-wireguard-star> Note: 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.
container-test-run-dm-wireguard-star> ░ Spawning container peer1 on /build/vm-state-peer1.
container-test-run-dm-wireguard-star> ░ Spawning container controller on /build/vm-state-controller.
container-test-run-dm-wireguard-star> controller # No journal files were found.
container-test-run-dm-wireguard-star> controller # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> peer1 # No journal files were found.
container-test-run-dm-wireguard-star> peer1 # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> controller # [1600540.932062] controller systemd-journald[108]: Journal started
container-test-run-dm-wireguard-star> controller # [1600540.932119] controller systemd-journald[108]: Runtime Journal (/run/log/journal/afadfc71c82e415596820e439963ec42) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> controller # [1600540.934024] controller systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> controller # [1600540.946044] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [1600540.959962] controller systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> controller # [1600540.960720] controller systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [1600540.961597] controller systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [1600540.973483] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/afadfc71c82e415596820e439963ec42 is 1.468ms for 7 entries.
container-test-run-dm-wireguard-star> controller # [1600540.973483] controller systemd-journald[108]: System Journal (/var/log/journal/afadfc71c82e415596820e439963ec42) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> controller # [1600540.993698] controller systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> controller # [1600540.994066] controller systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> controller # [1600540.995111] controller systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> controller # [1600540.995231] controller systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> controller # [1600540.996158] controller systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> controller # [1600540.996209] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [1600540.997013] controller systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> controller # [1600540.997716] controller systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> controller # [1600540.997746] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> controller # [1600541.016195] controller systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> controller # [1600541.017665] controller systemd-tmpfiles[164]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1600541.017865] controller systemd-tmpfiles[164]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1600541.017999] controller systemd-tmpfiles[164]: fchmod() of /var/log/journal/afadfc71c82e415596820e439963ec42 failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1600541.018218] controller systemd-tmpfiles[164]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1600541.019676] controller systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> controller # [1600541.020768] controller systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> controller # [1600541.021531] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> controller # [1600541.035741] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> controller # [1600541.040950] controller systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> controller # [1600541.041860] controller systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> controller # [1600541.052241] controller systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> controller # [1600541.113149] controller systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> controller # [1600541.113297] controller systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> controller # [1600541.113508] controller systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> controller # [1600541.114470] controller systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer1 # [1600540.920603] peer1 systemd-journald[99]: Journal started
container-test-run-dm-wireguard-star> controller # [1600541.833993] controller systemd-networkd[228]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1600541.834088] controller systemd-networkd[228]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1600540.920660] peer1 systemd-journald[99]: Runtime Journal (/run/log/journal/f93a2ddf982b4e5e9fba7e56654fd7b1) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> controller # [1600541.844546] controller systemd-networkd[228]: /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.
container-test-run-dm-wireguard-star> peer1 # [1600540.930492] peer1 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> controller # [1600541.844714] controller systemd-networkd[228]: /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.
container-test-run-dm-wireguard-star> peer1 # [1600540.943316] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [1600541.844864] controller systemd-networkd[228]: lo: Link UP
container-test-run-dm-wireguard-star> peer1 # [1600540.957379] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> controller # [1600541.844869] controller systemd-networkd[228]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1600540.958238] peer1 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [1600541.845046] controller systemd-networkd[228]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer1 # [1600540.958896] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [1600541.845464] controller systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer1 # [1600540.967355] peer1 systemd-journald[99]: Time spent on flushing to /var/log/journal/f93a2ddf982b4e5e9fba7e56654fd7b1 is 1.229ms for 7 entries.
container-test-run-dm-wireguard-star> controller # [1600541.846200] controller systemd-networkd[228]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer1 # [1600540.967355] peer1 systemd-journald[99]: System Journal (/var/log/journal/f93a2ddf982b4e5e9fba7e56654fd7b1) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> controller # [1600541.846545] controller systemd-networkd[228]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer1 # [1600540.984188] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> controller # [1600541.846638] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer1 # [1600540.984393] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> controller # [1600541.846936] controller systemd-networkd[228]: eth1: Link UP
container-test-run-dm-wireguard-star> peer1 # [1600540.985656] peer1 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> controller # [1600541.847151] controller systemd-networkd[228]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1600540.985779] peer1 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [1600540.986561] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer1 # [1600540.986607] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [1600540.987508] peer1 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer1 # [1600540.988521] peer1 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer1 # [1600540.988549] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer1 # [1600541.008497] peer1 systemd-tmpfiles[156]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1600541.008790] peer1 systemd-tmpfiles[156]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1600541.008943] peer1 systemd-tmpfiles[156]: fchmod() of /var/log/journal/f93a2ddf982b4e5e9fba7e56654fd7b1 failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1600541.009163] peer1 systemd-tmpfiles[156]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1600541.012267] peer1 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer1 # [1600541.012576] peer1 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer1 # [1600541.014212] peer1 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer1 # [1600541.014925] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer1 # [1600541.029598] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer1 # [1600541.037114] peer1 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer1 # [1600541.038303] peer1 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer1 # [1600541.048584] peer1 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [1600541.088677] peer1 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer1 # [1600541.088831] peer1 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer1 # [1600541.089050] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer1 # [1600541.090090] peer1 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer1 # [1600541.775990] peer1 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1600541.776111] peer1 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1600541.786384] peer1 systemd-networkd[217]: /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.
container-test-run-dm-wireguard-star> peer1 # [1600541.786554] peer1 systemd-networkd[217]: /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.
container-test-run-dm-wireguard-star> peer1 # [1600541.786699] peer1 systemd-networkd[217]: lo: Link UP
container-test-run-dm-wireguard-star> peer1 # [1600541.786703] peer1 systemd-networkd[217]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1600541.786899] peer1 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer1 # [1600541.787262] peer1 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer1 # [1600541.788082] peer1 systemd-networkd[217]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer1 # [1600541.788401] peer1 systemd-networkd[217]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer1 # [1600541.788703] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer1 # [1600541.788788] peer1 systemd-networkd[217]: eth1: Link UP
container-test-run-dm-wireguard-star> peer1 # [1600541.789001] peer1 systemd-networkd[217]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1600541.828594] peer1 systemd-networkd[217]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer1 # [1600541.828598] peer1 systemd-networkd[217]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1600541.838181] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer1 # [1600541.932820] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1600541.877013] controller systemd-networkd[228]: wg-star: Link UP
container-test-run-dm-wireguard-star> controller # [1600541.877018] controller systemd-networkd[228]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> controller # [1600541.878582] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> controller # [1600541.940536] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1600542.185653] controller systemd-resolved[140]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> controller # [1600542.185666] controller systemd-resolved[140]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> controller # [1600542.185670] controller systemd-resolved[140]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer1 # [1600542.155010] peer1 systemd-resolved[135]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer1 # [1600542.155021] peer1 systemd-resolved[135]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer1 # [1600542.155024] peer1 systemd-resolved[135]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer1 # [1600542.155058] peer1 systemd-resolved[135]: 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 test
container-test-run-dm-wireguard-star> peer1 # [1600542.177347] peer1 systemd-resolved[135]: Using system hostname 'peer1'.
container-test-run-dm-wireguard-star> peer1 # [1600542.178907] peer1 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer1 # [1600542.179038] peer1 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer1 # [1600542.179164] peer1 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer1 # [1600542.179324] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1600542.179372] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer1 # [1600542.179408] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [1600542.179451] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer1 # [1600542.179486] peer1 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer1 # [1600542.179553] peer1 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer1 # [1600542.179767] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer1 # [1600542.179963] peer1 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer1 # [1600542.180226] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer1 # [1600542.180277] peer1 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer1 # [1600542.180370] peer1 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer1 # [1600542.183231] peer1 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer1 # [1600542.184598] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer1 # [1600542.186480] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer1 # [1600542.188835] peer1 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer1 # [1600542.239549] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer1 # [1600542.431820] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer1 # [1600542.431898] peer1 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> controller # [1600542.185703] controller systemd-resolved[140]: 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 test
container-test-run-dm-wireguard-star> controller # [1600542.207937] controller systemd-resolved[140]: Using system hostname 'controller'.
container-test-run-dm-wireguard-star> controller # [1600542.210295] controller systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> controller # [1600542.210378] controller systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> controller # [1600542.210564] controller systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> controller # [1600542.210662] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.
container-test-run-dm-wireguard-star> controller # [1600542.210690] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [1600542.210712] controller systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> controller # [1600542.210733] controller systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> controller # [1600542.210763] controller systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> controller # [1600542.210885] controller systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> controller # [1600542.210998] controller systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> controller # [1600542.211110] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> controller # [1600542.211130] controller systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> controller # [1600542.211172] controller systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> controller # [1600542.220541] controller systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> controller # [1600542.221336] controller systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> controller # [1600542.222297] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> controller # [1600542.223597] controller systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> controller # [1600542.240473] controller systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> controller # [1600542.426048] controller systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> controller # [1600542.426120] controller systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> controller # [1600542.426398] controller nsncd[235]: Sep 22 20:02:42.411 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-dm-wireguard-star> controller # [1600542.426233] controller systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> controller # [1600542.427958] controller systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> controller # [1600542.429097] controller systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> controller # [1600542.468220] controller systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> controller # [1600542.469563] controller systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> controller # [1600542.469615] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> controller # [1600542.469640] controller systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> controller # [1600542.570287] controller dbus-broker-launch[236]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> controller # [1600542.571866] controller dbus-broker-launch[236]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> controller # [1600542.571866] controller dbus-broker-launch[236]: Invalid user-name in /nix/store/1xajrw28md93r10rcdy6n2iv2iyng085-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> controller # [1600542.572303] controller systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> controller # [1600542.581116] controller dbus-broker-launch[236]: Ready
container-test-run-dm-wireguard-star> peer1 # [1600542.432136] peer1 nsncd[224]: Sep 22 20:02:42.417 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-dm-wireguard-star> peer1 # [1600542.431973] peer1 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [1600542.460422] peer1 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer1 # [1600542.461689] peer1 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer1 # [1600542.474425] peer1 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer1 # [1600542.476281] peer1 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer1 # [1600542.476353] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer1 # [1600542.476392] peer1 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer1 # [1600542.592199] peer1 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer1 # [1600542.593561] peer1 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer1 # [1600542.593561] peer1 dbus-broker-launch[225]: Invalid user-name in /nix/store/1xajrw28md93r10rcdy6n2iv2iyng085-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer1 # [1600542.594010] peer1 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer1 # [1600542.602600] peer1 dbus-broker-launch[225]: Ready
container-test-run-dm-wireguard-star> controller # [1600543.008111] controller systemd-networkd[228]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> controller # [1600543.046869] controller data-mesher[233]: time=2026-09-22T20:02:43.032Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-dm-wireguard-star> controller # [1600543.048164] controller data-mesher[233]: time=2026-09-22T20:02:43.033Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us: [/dns/controller.clan/tcp/7946]} {12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt: [/dns/peer1.clan/tcp/7946]} {12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> controller # [1600543.048261] controller data-mesher[233]: time=2026-09-22T20:02:43.034Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> controller # [1600543.061971] controller data-mesher[233]: time=2026-09-22T20:02:43.047Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> controller # [1600543.062100] controller data-mesher[233]: time=2026-09-22T20:02:43.047Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> controller # [1600543.066212] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="libp2p host created" peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us 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::294a:40f8:557b:bb3c/tcp/7946]"
container-test-run-dm-wireguard-star> controller # [1600543.066263] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> controller # [1600543.066263] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> controller # [1600543.066263] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> controller # [1600543.066263] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> controller # [1600543.066377] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> controller # [1600543.066441] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> controller # [1600543.066486] controller data-mesher[233]: time=2026-09-22T20:02:43.052Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> controller # [1600543.089173] controller data-mesher[233]: time=2026-09-22T20:02:43.074Z level=INFO msg="peer connected" peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [1600543.076865] peer1 data-mesher[222]: time=2026-09-22T20:02:43.062Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-dm-wireguard-star> peer1 # [1600543.077943] peer1 data-mesher[222]: time=2026-09-22T20:02:43.063Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us: [/dns/controller.clan/tcp/7946]} {12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt: [/dns/peer1.clan/tcp/7946]} {12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer1 # [1600543.077943] peer1 data-mesher[222]: time=2026-09-22T20:02:43.063Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer1 # [1600543.079015] peer1 data-mesher[222]: time=2026-09-22T20:02:43.064Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer1 # [1600543.079115] peer1 data-mesher[222]: time=2026-09-22T20:02:43.064Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer1 # [1600543.083093] peer1 data-mesher[222]: time=2026-09-22T20:02:43.068Z level=INFO msg="libp2p host created" peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt 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::8472:4a5:acd7:7705/tcp/7946]"
container-test-run-dm-wireguard-star> peer1 # [1600543.083142] peer1 data-mesher[222]: time=2026-09-22T20:02:43.068Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer1 # [1600543.083142] peer1 data-mesher[222]: time=2026-09-22T20:02:43.068Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [1600543.083142] peer1 data-mesher[222]: time=2026-09-22T20:02:43.068Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [1600543.083142] peer1 data-mesher[222]: time=2026-09-22T20:02:43.068Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer1 # [1600543.083307] peer1 data-mesher[222]: time=2026-09-22T20:02:43.069Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer1 # [1600543.083359] peer1 data-mesher[222]: time=2026-09-22T20:02:43.069Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer1 # [1600543.083397] peer1 data-mesher[222]: time=2026-09-22T20:02:43.069Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer1 # [1600543.088503] peer1 data-mesher[222]: time=2026-09-22T20:02:43.074Z level=INFO msg="peer connected" peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [1600543.279541] peer1 systemd-logind[242]: New seat seat0.
container-test-run-dm-wireguard-star> peer1 # [1600543.279728] peer1 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer1 # [1600543.281974] peer1 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> controller # [1600543.295100] controller systemd-logind[253]: New seat seat0.
container-test-run-dm-wireguard-star> controller # [1600543.295314] controller systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> controller # [1600543.328702] controller systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> controller # [1600543.343332] controller systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1600543.343462] controller systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> peer1 # [1600543.338699] peer1 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1600543.338986] peer1 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> peer1 # [1600543.488447] peer1 systemd-networkd[217]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> controller: still waiting for container 'controller' to reach ready state...
container-test-run-dm-wireguard-star> peer1 # [1600553.067536] peer1 data-mesher[222]: time=2026-09-22T20:02:53.053Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600553.067536] peer1 data-mesher[222]: time=2026-09-22T20:02:53.053Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600553.083533] peer1 data-mesher[222]: time=2026-09-22T20:02:53.069Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer1 # [1600553.083641] peer1 data-mesher[222]: time=2026-09-22T20:02:53.069Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600553.084404] peer1 data-mesher[222]: time=2026-09-22T20:02:53.070Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600553.084404] peer1 data-mesher[222]: time=2026-09-22T20:02:53.070Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600553.084751] peer1 data-mesher[222]: time=2026-09-22T20:02:53.070Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer1 # [1600553.084751] peer1 data-mesher[222]: time=2026-09-22T20:02:53.070Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer1 # [1600553.084795] peer1 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer1 # [1600553.104856] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1600553.230879] peer1 data-mesher[222]: time=2026-09-22T20:02:53.216Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 status=204
container-test-run-dm-wireguard-star> peer1 # [1600553.231082] peer1 dm-wg-star-publish[287]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer1 # [1600553.233832] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1600553.234410] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1600553.234684] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1600553.236411] peer1 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer1 # [1600553.350547] peer1 dm-wg-star-reconfig[314]: No controller data available yet, skipping
container-test-run-dm-wireguard-star> controller # [1600553.066793] controller data-mesher[233]: time=2026-09-22T20:02:53.052Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> controller # [1600553.067525] controller data-mesher[233]: time=2026-09-22T20:02:53.052Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600553.067870] controller data-mesher[233]: time=2026-09-22T20:02:53.053Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600553.067870] controller data-mesher[233]: time=2026-09-22T20:02:53.053Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600553.067991] controller data-mesher[233]: time=2026-09-22T20:02:53.053Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> controller # [1600553.068203] controller data-mesher[233]: time=2026-09-22T20:02:53.053Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> controller # [1600553.068224] controller systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> controller # [1600553.070363] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...
container-test-run-dm-wireguard-star> controller # [1600553.084175] controller data-mesher[233]: time=2026-09-22T20:02:53.069Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600553.084175] controller data-mesher[233]: time=2026-09-22T20:02:53.070Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600553.143978] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1600553.146686] controller data-mesher[233]: time=2026-09-22T20:02:53.132Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204
container-test-run-dm-wireguard-star> controller # [1600553.146807] controller dm-wg-star-publish[297]: Status: 204 No Content
container-test-run-dm-wireguard-star> controller # [1600553.150568] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.
container-test-run-dm-wireguard-star> controller # [1600553.151340] controller systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> controller # [1600553.239692] controller dm-wg-star-reconfig[322]: No peer data available yet, skipping
container-test-run-dm-wireguard-star> controller # [1600553.241100] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1600553.268335] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> controller # [1600553.268925] controller systemd[1]: Startup finished in 12.780s.
container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 13.68 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for unit data-mesher.service
container-test-run-dm-wireguard-star> peer1: (finished: waiting for unit data-mesher.service, in 0.02 seconds)
container-test-run-dm-wireguard-star> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-dm-wireguard-star>     File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-dm-wireguard-star>     File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1600553.351509] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1600553.351671] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1600553.352127] peer1 systemd[1]: Startup finished in 12.890s.
container-test-run-dm-wireguard-star> controller # [1600558.070303] controller data-mesher[233]: time=2026-09-22T20:02:58.056Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1600558.071036] controller data-mesher[233]: time=2026-09-22T20:02:58.056Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600558.071592] controller data-mesher[233]: time=2026-09-22T20:02:58.057Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600558.072041] controller data-mesher[233]: time=2026-09-22T20:02:58.057Z level=INFO msg="received file request" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1600558.072235] controller data-mesher[233]: time=2026-09-22T20:02:58.058Z level=DEBUG msg="new file detected" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1600558.072310] controller data-mesher[233]: time=2026-09-22T20:02:58.058Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600558.072310] controller data-mesher[233]: time=2026-09-22T20:02:58.058Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1600558.072413] controller data-mesher[233]: time=2026-09-22T20:02:58.058Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1600558.072413] controller data-mesher[233]: time=2026-09-22T20:02:58.058Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 20:02:53.213 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600558.073758] controller data-mesher[233]: time=2026-09-22T20:02:58.059Z level=INFO msg="file transfer complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1600558.078144] controller data-mesher[233]: time=2026-09-22T20:02:58.063Z level=INFO msg="download complete" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 20:02:53.213 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt written=true elapsed=5.750438ms
container-test-run-dm-wireguard-star> controller # [1600558.085263] controller data-mesher[233]: time=2026-09-22T20:02:58.071Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600558.085263] controller data-mesher[233]: time=2026-09-22T20:02:58.071Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600558.106128] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1600558.225646] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1600558.225799] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1600558.071152] peer1 data-mesher[222]: time=2026-09-22T20:02:58.056Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600558.071152] peer1 data-mesher[222]: time=2026-09-22T20:02:58.056Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600558.071909] peer1 data-mesher[222]: time=2026-09-22T20:02:58.057Z level=DEBUG msg="new file detected" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1600558.071909] peer1 data-mesher[222]: time=2026-09-22T20:02:58.057Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1600558.071909] peer1 data-mesher[222]: time=2026-09-22T20:02:58.057Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:53.124 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600558.072960] peer1 data-mesher[222]: time=2026-09-22T20:02:58.058Z level=INFO msg="received file request" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer1 # [1600558.075588] peer1 data-mesher[222]: time=2026-09-22T20:02:58.061Z level=INFO msg="file transfer complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer1 # [1600558.077968] peer1 data-mesher[222]: time=2026-09-22T20:02:58.063Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:53.124 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us written=true elapsed=6.531609ms
container-test-run-dm-wireguard-star> peer1 # [1600558.084777] peer1 data-mesher[222]: time=2026-09-22T20:02:58.070Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1600558.084849] peer1 data-mesher[222]: time=2026-09-22T20:02:58.070Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600558.085814] peer1 data-mesher[222]: time=2026-09-22T20:02:58.071Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600558.086432] peer1 data-mesher[222]: time=2026-09-22T20:02:58.072Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600558.086496] peer1 data-mesher[222]: time=2026-09-22T20:02:58.072Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1600558.106285] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1600558.223425] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1600558.223748] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.05 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q .
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q ., in 0.01 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: wg show wg-star peers | grep -q .
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: wg show wg-star peers | grep -q .
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::294a:40f8:557b:bb3c
container-test-run-dm-wireguard-star> controller # [1600563.073078] controller data-mesher[233]: time=2026-09-22T20:03:03.058Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1600563.073825] controller data-mesher[233]: time=2026-09-22T20:03:03.058Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600563.074918] controller data-mesher[233]: time=2026-09-22T20:03:03.060Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600563.075550] controller data-mesher[233]: time=2026-09-22T20:03:03.061Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600563.075631] controller data-mesher[233]: time=2026-09-22T20:03:03.061Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1600563.088283] controller data-mesher[233]: time=2026-09-22T20:03:03.074Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600563.088283] controller data-mesher[233]: time=2026-09-22T20:03:03.074Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer1 # [1600563.073969] peer1 data-mesher[222]: time=2026-09-22T20:03:03.059Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600563.073969] peer1 data-mesher[222]: time=2026-09-22T20:03:03.059Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600563.087468] peer1 data-mesher[222]: time=2026-09-22T20:03:03.073Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1600563.087468] peer1 data-mesher[222]: time=2026-09-22T20:03:03.073Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600563.089524] peer1 data-mesher[222]: time=2026-09-22T20:03:03.075Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600563.090153] peer1 data-mesher[222]: time=2026-09-22T20:03:03.075Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600563.090221] peer1 data-mesher[222]: time=2026-09-22T20:03:03.076Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::294a:40f8:557b:bb3c, in 6.02 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705, in 0.01 seconds)
container-test-run-dm-wireguard-star> controller: must succeed: wg show wg-star peers | wc -l
container-test-run-dm-wireguard-star> controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: systemd-nspawn running (pid 785)
container-test-run-dm-wireguard-star> peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...
container-test-run-dm-wireguard-star> peer2: waiting for unit data-mesher.service
container-test-run-dm-wireguard-star> nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-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.
container-test-run-dm-wireguard-star> Note: 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.
container-test-run-dm-wireguard-star> ░ Spawning container peer2 on /build/vm-state-peer2.
container-test-run-dm-wireguard-star> peer2 # No journal files were found.
container-test-run-dm-wireguard-star> peer2 # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> peer2 # [1600565.720533] peer2 systemd-journald[100]: Journal started
container-test-run-dm-wireguard-star> peer2 # [1600565.720589] peer2 systemd-journald[100]: Runtime Journal (/run/log/journal/e9a34fbb05ab4209bbbe028c7c41b5a8) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> peer2 # [1600565.724631] peer2 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> peer2 # [1600565.736216] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> peer2 # [1600565.749505] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer2 # [1600565.750365] peer2 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> peer2 # [1600565.751169] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> peer2 # [1600565.760363] peer2 systemd-journald[100]: Time spent on flushing to /var/log/journal/e9a34fbb05ab4209bbbe028c7c41b5a8 is 1.608ms for 7 entries.
container-test-run-dm-wireguard-star> peer2 # [1600565.760363] peer2 systemd-journald[100]: System Journal (/var/log/journal/e9a34fbb05ab4209bbbe028c7c41b5a8) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer2 # [1600565.768876] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer2 # [1600565.769194] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer2 # [1600565.770160] peer2 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [1600565.770280] peer2 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [1600565.771094] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer2 # [1600565.771144] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [1600565.772060] peer2 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer2 # [1600565.772728] peer2 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer2 # [1600565.772758] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer2 # [1600565.790389] peer2 systemd-tmpfiles[148]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1600565.790601] peer2 systemd-tmpfiles[148]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1600565.791271] peer2 systemd-tmpfiles[148]: fchmod() of /var/log/journal/e9a34fbb05ab4209bbbe028c7c41b5a8 failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1600565.791506] peer2 systemd-tmpfiles[148]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1600565.793021] peer2 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer2 # [1600565.794066] peer2 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer2 # [1600565.794939] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer2 # [1600565.802579] peer2 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer2 # [1600565.810213] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer2 # [1600565.818931] peer2 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer2 # [1600565.820126] peer2 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer2 # [1600565.831828] peer2 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer2 # [1600565.895661] peer2 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer2 # [1600565.896106] peer2 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer2 # [1600565.896373] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer2 # [1600565.897506] peer2 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer2 # [1600566.554537] peer2 systemd-networkd[218]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1600566.554628] peer2 systemd-networkd[218]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1600566.565003] peer2 systemd-networkd[218]: /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.
container-test-run-dm-wireguard-star> peer2 # [1600566.565169] peer2 systemd-networkd[218]: /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.
container-test-run-dm-wireguard-star> peer2 # [1600566.565314] peer2 systemd-networkd[218]: lo: Link UP
container-test-run-dm-wireguard-star> peer2 # [1600566.565319] peer2 systemd-networkd[218]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1600566.565519] peer2 systemd-networkd[218]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer2 # [1600566.565918] peer2 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer2 # [1600566.566435] peer2 systemd-networkd[218]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer2 # [1600566.566756] peer2 systemd-networkd[218]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer2 # [1600566.567308] peer2 systemd-networkd[218]: eth1: Link UP
container-test-run-dm-wireguard-star> peer2 # [1600566.567738] peer2 systemd-networkd[218]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1600566.568093] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer2 # [1600566.600427] peer2 systemd-networkd[218]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer2 # [1600566.600433] peer2 systemd-networkd[218]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1600566.625338] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer2 # [1600566.731212] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1600566.882000] peer2 systemd-resolved[131]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer2 # [1600566.882013] peer2 systemd-resolved[131]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer2 # [1600566.882016] peer2 systemd-resolved[131]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer2 # [1600566.882050] peer2 systemd-resolved[131]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
container-test-run-dm-wireguard-star> peer2 # [1600566.903526] peer2 systemd-resolved[131]: Using system hostname 'peer2'.
container-test-run-dm-wireguard-star> peer2 # [1600566.905088] peer2 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer2 # [1600566.905210] peer2 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer2 # [1600566.905315] peer2 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer2 # [1600566.905468] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1600566.905521] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer2 # [1600566.905559] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [1600566.905604] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer2 # [1600566.905639] peer2 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer2 # [1600566.905710] peer2 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer2 # [1600566.905934] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer2 # [1600566.906140] peer2 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer2 # [1600566.906362] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer2 # [1600566.906405] peer2 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer2 # [1600566.906488] peer2 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer2 # [1600566.909092] peer2 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer2 # [1600566.910480] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer2 # [1600566.911837] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer2 # [1600566.913934] peer2 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer2 # [1600566.966871] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer2 # [1600567.142778] peer2 nsncd[225]: Sep 22 20:03:07.128 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-dm-wireguard-star> peer2 # [1600567.142702] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer2 # [1600567.142810] peer2 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [1600567.142919] peer2 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [1600567.145080] peer2 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer2 # [1600567.146406] peer2 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer2 # [1600567.188263] peer2 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer2 # [1600567.190012] peer2 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer2 # [1600567.190098] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer2 # [1600567.190146] peer2 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer2 # [1600567.270507] peer2 dbus-broker-launch[226]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer2 # [1600567.271969] peer2 dbus-broker-launch[226]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer2 # [1600567.271969] peer2 dbus-broker-launch[226]: Invalid user-name in /nix/store/1xajrw28md93r10rcdy6n2iv2iyng085-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer2 # [1600567.272436] peer2 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer2 # [1600567.280879] peer2 dbus-broker-launch[226]: Ready
container-test-run-dm-wireguard-star> peer2 # [1600567.682087] peer2 data-mesher[223]: time=2026-09-22T20:03:07.667Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-dm-wireguard-star> peer2 # [1600567.685839] peer2 data-mesher[223]: time=2026-09-22T20:03:07.671Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us: [/dns/controller.clan/tcp/7946]} {12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt: [/dns/peer1.clan/tcp/7946]} {12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer2 # [1600567.685839] peer2 data-mesher[223]: time=2026-09-22T20:03:07.671Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer2 # [1600567.687961] peer2 data-mesher[223]: time=2026-09-22T20:03:07.673Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer2 # [1600567.688128] peer2 data-mesher[223]: time=2026-09-22T20:03:07.673Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer2 # [1600567.693402] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="libp2p host created" peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j 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::bc72:afe2:902:125/tcp/7946]"
container-test-run-dm-wireguard-star> peer2 # [1600567.693486] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [1600567.693486] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [1600567.693486] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer2 # [1600567.693486] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer2 # [1600567.693661] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer2 # [1600567.693661] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer2 # [1600567.693750] peer2 data-mesher[223]: time=2026-09-22T20:03:07.679Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer2 # [1600567.701702] peer2 data-mesher[223]: time=2026-09-22T20:03:07.687Z level=INFO msg="peer connected" peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1600567.711325] peer2 data-mesher[223]: time=2026-09-22T20:03:07.697Z level=INFO msg="peer connected" peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> controller # [1600567.703527] controller data-mesher[233]: time=2026-09-22T20:03:07.689Z level=INFO msg="peer connected" peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [1600567.712131] peer1 data-mesher[222]: time=2026-09-22T20:03:07.697Z level=INFO msg="peer connected" peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1600567.954449] peer2 systemd-logind[243]: New seat seat0.
container-test-run-dm-wireguard-star> peer2 # [1600567.954683] peer2 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer2 # [1600567.956994] peer2 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer2 # [1600568.000150] peer2 systemd-networkd[218]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer2 # [1600568.002967] peer2 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1600568.003278] peer2 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> peer2 # [1600568.077448] peer2 data-mesher[223]: time=2026-09-22T20:03:08.063Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1600568.077448] peer2 data-mesher[223]: time=2026-09-22T20:03:08.063Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1600568.077977] peer2 data-mesher[223]: time=2026-09-22T20:03:08.063Z level=DEBUG msg="new file detected" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer2 # [1600568.078043] peer2 data-mesher[223]: time=2026-09-22T20:03:08.063Z level=DEBUG msg="new file detected" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [1600568.078149] peer2 data-mesher[223]: time=2026-09-22T20:03:08.063Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer2 # [1600568.078149] peer2 data-mesher[223]: time=2026-09-22T20:03:08.063Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [1600568.078251] peer2 data-mesher[223]: time=2026-09-22T20:03:08.064Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:53.124 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1600568.078251] peer2 data-mesher[223]: time=2026-09-22T20:03:08.064Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 20:02:53.213 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1600568.083981] peer2 data-mesher[223]: time=2026-09-22T20:03:08.069Z level=INFO msg="download complete" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 20:02:53.213 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us written=true elapsed=5.763598ms
container-test-run-dm-wireguard-star> peer2 # [1600568.084997] peer2 data-mesher[223]: time=2026-09-22T20:03:08.070Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:53.124 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us written=true elapsed=6.787212ms
container-test-run-dm-wireguard-star> peer2 # [1600568.086507] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> controller # [1600568.076615] controller data-mesher[233]: time=2026-09-22T20:03:08.062Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1600568.076615] controller data-mesher[233]: time=2026-09-22T20:03:08.062Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1600568.078287] controller data-mesher[233]: time=2026-09-22T20:03:08.064Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1600568.078287] controller data-mesher[233]: time=2026-09-22T20:03:08.064Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1600568.078287] controller data-mesher[233]: time=2026-09-22T20:03:08.064Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1600568.078760] controller data-mesher[233]: time=2026-09-22T20:03:08.064Z level=INFO msg="received file request" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1600568.078844] controller data-mesher[233]: time=2026-09-22T20:03:08.064Z level=INFO msg="received file request" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1600568.080582] controller data-mesher[233]: time=2026-09-22T20:03:08.066Z level=INFO msg="file transfer complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1600568.081781] controller data-mesher[233]: time=2026-09-22T20:03:08.067Z level=INFO msg="file transfer complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1600568.091135] controller data-mesher[233]: time=2026-09-22T20:03:08.076Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600568.091135] controller data-mesher[233]: time=2026-09-22T20:03:08.076Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1600568.250734] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1600568.251036] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1600568.090459] peer1 data-mesher[222]: time=2026-09-22T20:03:08.076Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1600568.090459] peer1 data-mesher[222]: time=2026-09-22T20:03:08.076Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600568.092163] peer1 data-mesher[222]: time=2026-09-22T20:03:08.077Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600568.092746] peer1 data-mesher[222]: time=2026-09-22T20:03:08.078Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600568.092818] peer1 data-mesher[222]: time=2026-09-22T20:03:08.078Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1600573.078953] controller data-mesher[233]: time=2026-09-22T20:03:13.064Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1600573.078953] controller data-mesher[233]: time=2026-09-22T20:03:13.064Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1600573.080753] controller data-mesher[233]: time=2026-09-22T20:03:13.066Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1600573.081399] controller data-mesher[233]: time=2026-09-22T20:03:13.067Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1600573.081470] controller data-mesher[233]: time=2026-09-22T20:03:13.067Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1600573.094625] controller data-mesher[233]: time=2026-09-22T20:03:13.080Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600573.094625] controller data-mesher[233]: time=2026-09-22T20:03:13.080Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1600573.079903] peer2 data-mesher[223]: time=2026-09-22T20:03:13.065Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1600573.079903] peer2 data-mesher[223]: time=2026-09-22T20:03:13.065Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600573.094140] peer1 data-mesher[222]: time=2026-09-22T20:03:13.079Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1600573.094140] peer1 data-mesher[222]: time=2026-09-22T20:03:13.079Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600573.095644] peer1 data-mesher[222]: time=2026-09-22T20:03:13.081Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600573.096246] peer1 data-mesher[222]: time=2026-09-22T20:03:13.082Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600573.096347] peer1 data-mesher[222]: time=2026-09-22T20:03:13.082Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state...
container-test-run-dm-wireguard-star> peer2 # [1600577.693896] peer2 data-mesher[223]: time=2026-09-22T20:03:17.679Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer2 # [1600577.693896] peer2 data-mesher[223]: time=2026-09-22T20:03:17.679Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1600577.695987] peer2 data-mesher[223]: time=2026-09-22T20:03:17.681Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1600577.696656] peer2 data-mesher[223]: time=2026-09-22T20:03:17.682Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1600577.696731] peer2 data-mesher[223]: time=2026-09-22T20:03:17.682Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer2 # [1600577.696866] peer2 data-mesher[223]: time=2026-09-22T20:03:17.682Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer2 # [1600577.697108] peer2 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer2 # [1600577.699345] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1600577.859406] peer2 data-mesher[223]: time=2026-09-22T20:03:17.845Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg status=204
container-test-run-dm-wireguard-star> peer2 # [1600577.859773] peer2 dm-wg-star-publish[299]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer2 # [1600577.861827] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1600577.863687] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1600577.864052] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1600577.865050] peer2 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer1 # [1600577.694900] peer1 data-mesher[222]: time=2026-09-22T20:03:17.680Z level=INFO msg="received state sync from peer" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1600577.694900] peer1 data-mesher[222]: time=2026-09-22T20:03:17.680Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer2 # [1600578.023480] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1600578.023794] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1600578.024318] peer2 systemd[1]: Startup finished in 12.774s.
container-test-run-dm-wireguard-star> peer2 # [1600578.097726] peer2 data-mesher[223]: time=2026-09-22T20:03:18.083Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1600578.097800] peer2 data-mesher[223]: time=2026-09-22T20:03:18.083Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1600578.100088] peer2 data-mesher[223]: time=2026-09-22T20:03:18.085Z level=INFO msg="received file request" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer2 # [1600578.101008] peer2 data-mesher[223]: time=2026-09-22T20:03:18.086Z level=INFO msg="file transfer complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer2: (finished: waiting for unit data-mesher.service, in 13.67 seconds)
container-test-run-dm-wireguard-star> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-dm-wireguard-star>     File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1
container-test-run-dm-wireguard-star> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-dm-wireguard-star>     File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> controller # [1600578.081915] controller data-mesher[233]: time=2026-09-22T20:03:18.067Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1600578.081915] controller data-mesher[233]: time=2026-09-22T20:03:18.067Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600578.083669] controller data-mesher[233]: time=2026-09-22T20:03:18.069Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600578.084282] controller data-mesher[233]: time=2026-09-22T20:03:18.070Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1600578.084358] controller data-mesher[233]: time=2026-09-22T20:03:18.070Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1600578.082753] peer1 data-mesher[222]: time=2026-09-22T20:03:18.068Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600578.082753] peer1 data-mesher[222]: time=2026-09-22T20:03:18.068Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600578.096796] peer1 data-mesher[222]: time=2026-09-22T20:03:18.082Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1600578.096956] peer1 data-mesher[222]: time=2026-09-22T20:03:18.082Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600578.098768] peer1 data-mesher[222]: time=2026-09-22T20:03:18.084Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1600578.099329] peer1 data-mesher[222]: time=2026-09-22T20:03:18.085Z level=DEBUG msg="new file detected" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer1 # [1600578.099497] peer1 data-mesher[222]: time=2026-09-22T20:03:18.085Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer1 # [1600578.099612] peer1 data-mesher[222]: time=2026-09-22T20:03:18.085Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 20:03:17.84 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1600578.100034] peer1 data-mesher[222]: time=2026-09-22T20:03:18.085Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600578.100034] peer1 data-mesher[222]: time=2026-09-22T20:03:18.085Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1600578.104311] peer1 data-mesher[222]: time=2026-09-22T20:03:18.090Z level=INFO msg="download complete" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 20:03:17.84 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j written=true elapsed=4.757225ms
container-test-run-dm-wireguard-star> peer1 # [1600578.107404] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1600578.260524] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1600578.260878] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1600582.697008] peer2 data-mesher[223]: time=2026-09-22T20:03:22.682Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-dm-wireguard-star> peer2 # [1600582.697008] peer2 data-mesher[223]: time=2026-09-22T20:03:22.682Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1600582.699480] peer2 data-mesher[223]: time=2026-09-22T20:03:22.685Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1600582.700681] peer2 data-mesher[223]: time=2026-09-22T20:03:22.686Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1600582.700762] peer2 data-mesher[223]: time=2026-09-22T20:03:22.686Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1600582.697839] peer1 data-mesher[222]: time=2026-09-22T20:03:22.683Z level=INFO msg="received state sync from peer" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1600582.697839] peer1 data-mesher[222]: time=2026-09-22T20:03:22.683Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1, in 5.09 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: waiting for success: wg show wg-star peers | grep -q .
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::294a:40f8:557b:bb3c
container-test-run-dm-wireguard-star> controller # [1600583.084774] controller data-mesher[233]: time=2026-09-22T20:03:23.070Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1600583.084774] controller data-mesher[233]: time=2026-09-22T20:03:23.070Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1600583.086558] controller data-mesher[233]: time=2026-09-22T20:03:23.072Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1600583.087162] controller data-mesher[233]: time=2026-09-22T20:03:23.072Z level=DEBUG msg="new file detected" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> controller # [1600583.087340] controller data-mesher[233]: time=2026-09-22T20:03:23.073Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> controller # [1600583.087406] controller data-mesher[233]: time=2026-09-22T20:03:23.073Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 20:03:17.84 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1600583.087919] controller data-mesher[233]: time=2026-09-22T20:03:23.073Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1600583.087919] controller data-mesher[233]: time=2026-09-22T20:03:23.073Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1600583.091487] controller data-mesher[233]: time=2026-09-22T20:03:23.077Z level=INFO msg="download complete" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 20:03:17.84 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j written=true elapsed=4.134337ms
container-test-run-dm-wireguard-star> controller # [1600583.094422] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1600583.100686] controller data-mesher[233]: time=2026-09-22T20:03:23.086Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600583.100686] controller data-mesher[233]: time=2026-09-22T20:03:23.086Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1600583.262469] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1600583.262831] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1600583.085426] peer2 data-mesher[223]: time=2026-09-22T20:03:23.071Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1600583.085426] peer2 data-mesher[223]: time=2026-09-22T20:03:23.071Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1600583.087872] peer2 data-mesher[223]: time=2026-09-22T20:03:23.073Z level=INFO msg="received file request" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer2 # [1600583.088716] peer2 data-mesher[223]: time=2026-09-22T20:03:23.074Z level=INFO msg="file transfer complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer1 # [1600583.100086] peer1 data-mesher[222]: time=2026-09-22T20:03:23.085Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1600583.100224] peer1 data-mesher[222]: time=2026-09-22T20:03:23.085Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600583.102254] peer1 data-mesher[222]: time=2026-09-22T20:03:23.088Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1600583.103369] peer1 data-mesher[222]: time=2026-09-22T20:03:23.089Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1600583.103444] peer1 data-mesher[222]: time=2026-09-22T20:03:23.089Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::294a:40f8:557b:bb3c, in 0.67 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705, in 0.01 seconds)
container-test-run-dm-wireguard-star> (finished: run the VM test script, in 44.34 seconds)
container-test-run-dm-wireguard-star> test script finished in 44.38s
container-test-run-dm-wireguard-star> cleanup
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 50)
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 53)
container-test-run-dm-wireguard-star> Container controller terminated by signal KILL.
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 785)
container-test-run-dm-wireguard-star> Container peer1 terminated by signal KILL.
container-test-run-dm-wireguard-star> Container peer2 terminated by signal KILL.
container-test-run-dm-wireguard-star> (finished: cleanup, in 0.70 seconds)

post-build step Upload to niks3: ok
time=2026-09-22T20:03:25.408Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-09-22T20:03:27.353Z level=INFO msg="Uploading 1 narinfos"
time=2026-09-22T20:03:27.997Z level=INFO msg="Upload complete. (2.698s)"