these 24 derivations will be built:
  /nix/store/vfsvphd2j2lh6grnazc9gjw36v03s8bb-system-path.drv
  /nix/store/vnknk23amihi5c5xf3z5scikc1kdwx55-dbus-1.drv
  /nix/store/y2m2zv0d1jsn9ww264pj1nagqpwf4n6l-X-Restart-Triggers-dbus-broker.drv
  /nix/store/wv2ivmx94rzgvx07fp2mymc01c8ji1ia-unit-dbus-broker.service.drv
  /nix/store/1ablv0r7vp6dbwma3h0rgvn1hrqwxmiz-system-units.drv
  /nix/store/6nnsh8cp2c67w16chzgq106dpscw2abr-unit-dbus-broker.service.drv
  /nix/store/q4ffjymm9lfhr6jjp37akddkvvlvvf2m-user-units.drv
  /nix/store/frb961hbbw0fbi8aq4z3y4if3ibp55p3-etc.drv
  /nix/store/kwlc805g28f099rflp9w7xwpbf1sr0nh-activate.drv
  /nix/store/rg51d0h8788brz8cv6qgs8n036pn9lyk-nixos-system-peer2-test.drv
  /nix/store/07mdah92qrqjnfxcylaylb9xpwd1brry-run-peer2-nspawn.drv
  /nix/store/anwc5hrp61vaqr5v9z0ymn33zrq322h4-system-units.drv
  /nix/store/2qhhfg4w2x6bkzqhwwdb73597qgsh83h-etc.drv
  /nix/store/4l6pv6pfzywffb4318f6ql98mzxyjh50-system-units.drv
  /nix/store/r569a8rqphfpkqsfnl29ic070nhpmj8c-etc.drv
  /nix/store/m9dwfi8a3zg2d2lh2wgl01yx6bzwps2j-activate.drv
  /nix/store/330wwj84l1xgfv52702g3b9fnmajgz7p-nixos-system-controller-test.drv
  /nix/store/4fihhvkfdhwp6mnxbyn4l37wdvm0wnwj-run-controller-nspawn.drv
  /nix/store/am090gcr8cw8la2c4qiizjckyb25y620-activate.drv
  /nix/store/8wklvd7nbmxqhm60rr06lrwbny1wpgps-nixos-system-peer1-test.drv
  /nix/store/cgddvdpdcc0v6b3wrl5qbwg4n5dh8l9c-run-peer1-nspawn.drv
  /nix/store/na38l2hrgfkplgck4ynxnp108rcp37i2-driverConfiguration.json.drv
  /nix/store/g1yn0z9f265k4sj44d923f9ilz69jasg-nixos-test-driver-dm-wireguard-star.drv
  /nix/store/i5584c30wg0caxaisr133j5c1mlskqk4-container-test-run-dm-wireguard-star.drv
building '/nix/store/vfsvphd2j2lh6grnazc9gjw36v03s8bb-system-path.drv'
system-path> structuredAttrs is enabled
system-path> created 1731 symlinks in user environment
building '/nix/store/vnknk23amihi5c5xf3z5scikc1kdwx55-dbus-1.drv'
building '/nix/store/y2m2zv0d1jsn9ww264pj1nagqpwf4n6l-X-Restart-Triggers-dbus-broker.drv'
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
building '/nix/store/6nnsh8cp2c67w16chzgq106dpscw2abr-unit-dbus-broker.service.drv'
building '/nix/store/wv2ivmx94rzgvx07fp2mymc01c8ji1ia-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/q4ffjymm9lfhr6jjp37akddkvvlvvf2m-user-units.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/1ablv0r7vp6dbwma3h0rgvn1hrqwxmiz-system-units.drv'
building '/nix/store/4l6pv6pfzywffb4318f6ql98mzxyjh50-system-units.drv'
building '/nix/store/anwc5hrp61vaqr5v9z0ymn33zrq322h4-system-units.drv'
building '/nix/store/frb961hbbw0fbi8aq4z3y4if3ibp55p3-etc.drv'
building '/nix/store/2qhhfg4w2x6bkzqhwwdb73597qgsh83h-etc.drv'
building '/nix/store/r569a8rqphfpkqsfnl29ic070nhpmj8c-etc.drv'
building '/nix/store/kwlc805g28f099rflp9w7xwpbf1sr0nh-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/rg51d0h8788brz8cv6qgs8n036pn9lyk-nixos-system-peer2-test.drv'
building '/nix/store/am090gcr8cw8la2c4qiizjckyb25y620-activate.drv'
nixos-system-peer2-test> structuredAttrs is enabled
building '/nix/store/m9dwfi8a3zg2d2lh2wgl01yx6bzwps2j-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/07mdah92qrqjnfxcylaylb9xpwd1brry-run-peer2-nspawn.drv'
activate> structuredAttrs is enabled
building '/nix/store/8wklvd7nbmxqhm60rr06lrwbny1wpgps-nixos-system-peer1-test.drv'
building '/nix/store/330wwj84l1xgfv52702g3b9fnmajgz7p-nixos-system-controller-test.drv'
nixos-system-peer1-test> structuredAttrs is enabled
nixos-system-controller-test> structuredAttrs is enabled
building '/nix/store/cgddvdpdcc0v6b3wrl5qbwg4n5dh8l9c-run-peer1-nspawn.drv'
building '/nix/store/4fihhvkfdhwp6mnxbyn4l37wdvm0wnwj-run-controller-nspawn.drv'
building '/nix/store/na38l2hrgfkplgck4ynxnp108rcp37i2-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/g1yn0z9f265k4sj44d923f9ilz69jasg-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/i5584c30wg0caxaisr133j5c1mlskqk4-container-test-run-dm-wireguard-star.drv' on 'ssh-ng://builder@build-x86-01.clan.lol'
building '/nix/store/i5584c30wg0caxaisr133j5c1mlskqk4-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> ░ Spawning container peer1 on /build/vm-state-peer1.
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 controller on /build/vm-state-controller.
container-test-run-dm-wireguard-star> controller # [1601109.085270] controller systemd-journald[108]: Journal started
container-test-run-dm-wireguard-star> controller # [1601109.085295] controller systemd-journald[108]: Runtime Journal (/run/log/journal/7bb770041fd0405ba3ff7eebcd65b576) is 8M, max 3.7G, 3.7G free.
container-test-run-dm-wireguard-star> controller # [1601109.091434] controller systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> controller # [1601109.099172] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [1601109.107060] controller systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> controller # [1601109.107524] controller systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [1601109.107870] controller systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [1601109.130191] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/7bb770041fd0405ba3ff7eebcd65b576 is 909us for 7 entries.
container-test-run-dm-wireguard-star> controller # [1601109.130191] controller systemd-journald[108]: System Journal (/var/log/journal/7bb770041fd0405ba3ff7eebcd65b576) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> controller # [1601109.142379] controller systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> controller # [1601109.142669] controller systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> controller # [1601109.143357] controller systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> controller # [1601109.143416] controller systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> controller # [1601109.143882] controller systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> controller # [1601109.143910] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [1601109.085917] peer1 systemd-journald[99]: Journal started
container-test-run-dm-wireguard-star> peer1 # [1601109.085945] peer1 systemd-journald[99]: Runtime Journal (/run/log/journal/2094ed9f1343451397f904159bf8814a) is 8M, max 3.7G, 3.7G free.
container-test-run-dm-wireguard-star> peer1 # [1601109.092214] peer1 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> peer1 # [1601109.098930] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [1601109.144374] controller systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer1 # [1601109.106759] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer1 # [1601109.107198] peer1 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [1601109.144710] controller systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer1 # [1601109.107488] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [1601109.144724] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer1 # [1601109.130191] peer1 systemd-journald[99]: Time spent on flushing to /var/log/journal/2094ed9f1343451397f904159bf8814a is 983us for 7 entries.
container-test-run-dm-wireguard-star> controller # [1601109.155681] controller systemd-tmpfiles[187]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1601109.155869] controller systemd-tmpfiles[187]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1601109.156001] controller systemd-tmpfiles[187]: fchmod() of /var/log/journal/7bb770041fd0405ba3ff7eebcd65b576 failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1601109.156199] controller systemd-tmpfiles[187]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1601109.157143] controller systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> controller # [1601109.157634] controller systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer1 # [1601109.130191] peer1 systemd-journald[99]: System Journal (/var/log/journal/2094ed9f1343451397f904159bf8814a) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> controller # [1601109.157960] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer1 # [1601109.141963] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> controller # [1601109.164114] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer1 # [1601109.142229] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> controller # [1601109.169628] controller systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer1 # [1601109.143172] peer1 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> controller # [1601109.170195] controller systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer1 # [1601109.143225] peer1 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> controller # [1601109.177624] controller systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer1 # [1601109.143678] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> controller # [1601109.177715] controller systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer1 # [1601109.143706] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [1601109.177859] controller systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer1 # [1601109.144181] peer1 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> controller # [1601109.178355] controller systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer1 # [1601109.144577] peer1 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> controller # [1601109.182304] controller systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [1601109.144592] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> controller # [1601109.221228] controller systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer1 # [1601109.153905] peer1 systemd-tmpfiles[178]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1601109.154100] peer1 systemd-tmpfiles[178]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1601109.154197] peer1 systemd-tmpfiles[178]: fchmod() of /var/log/journal/2094ed9f1343451397f904159bf8814a failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1601109.154341] peer1 systemd-tmpfiles[178]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1601109.155101] peer1 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer1 # [1601109.155628] peer1 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer1 # [1601109.155986] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer1 # [1601109.163688] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer1 # [1601109.168863] peer1 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer1 # [1601109.169503] peer1 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer1 # [1601109.175334] peer1 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer1 # [1601109.175406] peer1 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer1 # [1601109.175538] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer1 # [1601109.176180] peer1 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer1 # [1601109.182299] peer1 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [1601109.221223] peer1 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> controller # [1601109.685398] controller systemd-networkd[226]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1601109.685474] controller systemd-networkd[226]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1601109.698889] controller systemd-networkd[226]: /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> controller # [1601109.699129] controller systemd-networkd[226]: /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> controller # [1601109.699199] controller systemd-networkd[226]: lo: Link UP
container-test-run-dm-wireguard-star> controller # [1601109.699202] controller systemd-networkd[226]: lo: Gained carrier
container-test-run-dm-wireguard-star> controller # [1601109.699350] controller systemd-networkd[226]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> controller # [1601109.699587] controller systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> controller # [1601109.699922] controller systemd-networkd[226]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> controller # [1601109.700103] controller systemd-networkd[226]: wg-star: netdev ready
container-test-run-dm-wireguard-star> controller # [1601109.700228] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> controller # [1601109.700232] controller systemd-networkd[226]: eth1: Link UP
container-test-run-dm-wireguard-star> controller # [1601109.700337] controller systemd-networkd[226]: eth1: Gained carrier
container-test-run-dm-wireguard-star> controller # [1601109.719482] controller systemd-networkd[226]: wg-star: Link UP
container-test-run-dm-wireguard-star> controller # [1601109.719485] controller systemd-networkd[226]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> controller # [1601109.723725] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer1 # [1601109.706793] peer1 systemd-networkd[215]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1601109.706867] peer1 systemd-networkd[215]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1601109.714995] peer1 systemd-networkd[215]: /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 # [1601109.715147] peer1 systemd-networkd[215]: /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 # [1601109.715228] peer1 systemd-networkd[215]: lo: Link UP
container-test-run-dm-wireguard-star> peer1 # [1601109.715231] peer1 systemd-networkd[215]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1601109.715374] peer1 systemd-networkd[215]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer1 # [1601109.715633] peer1 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer1 # [1601109.718185] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer1 # [1601109.718519] peer1 systemd-networkd[215]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer1 # [1601109.718828] peer1 systemd-networkd[215]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer1 # [1601109.718957] peer1 systemd-networkd[215]: eth1: Link UP
container-test-run-dm-wireguard-star> peer1 # [1601109.719341] peer1 systemd-networkd[215]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1601109.734853] peer1 systemd-networkd[215]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer1 # [1601109.734858] peer1 systemd-networkd[215]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1601109.735630] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> controller # [1601110.067321] controller systemd-resolved[146]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> controller # [1601110.067328] controller systemd-resolved[146]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> controller # [1601110.067331] controller systemd-resolved[146]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> controller # [1601110.067349] controller systemd-resolved[146]: 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 # [1601110.079680] controller systemd-resolved[146]: Using system hostname 'controller'.
container-test-run-dm-wireguard-star> controller # [1601110.084131] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1601110.084538] controller systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> controller # [1601110.084576] controller systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> controller # [1601110.084611] controller systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> controller # [1601110.084660] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.
container-test-run-dm-wireguard-star> controller # [1601110.084674] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [1601110.084686] controller systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> controller # [1601110.084696] controller systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> controller # [1601110.084714] controller systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> controller # [1601110.084778] controller systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> controller # [1601110.084839] controller systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> controller # [1601110.084904] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> controller # [1601110.084912] controller systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> controller # [1601110.084930] controller systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> controller # [1601110.085846] controller systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> controller # [1601110.086233] controller systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> controller # [1601110.086624] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> controller # [1601110.087224] controller systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> controller # [1601110.097569] controller systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> controller # [1601110.215270] controller nsncd[235]: Sep 22 20:02:04.638 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 # [1601110.215320] controller systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> controller # [1601110.215359] controller systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> controller # [1601110.215391] controller systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> controller # [1601110.234144] controller systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> controller # [1601110.234680] controller systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> controller # [1601110.240367] controller systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> controller # [1601110.240939] controller systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> controller # [1601110.240961] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> controller # [1601110.240972] controller systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer1 # [1601110.069783] peer1 systemd-resolved[136]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer1 # [1601110.069789] peer1 systemd-resolved[136]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer1 # [1601110.069792] peer1 systemd-resolved[136]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer1 # [1601110.069808] peer1 systemd-resolved[136]: 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 # [1601110.081309] peer1 systemd-resolved[136]: Using system hostname 'peer1'.
container-test-run-dm-wireguard-star> peer1 # [1601110.084443] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1601110.084881] peer1 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer1 # [1601110.084920] peer1 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer1 # [1601110.084958] peer1 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer1 # [1601110.085016] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1601110.085033] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer1 # [1601110.085045] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [1601110.085056] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer1 # [1601110.085068] peer1 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer1 # [1601110.085091] peer1 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer1 # [1601110.085162] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer1 # [1601110.085217] peer1 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer1 # [1601110.085295] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer1 # [1601110.085307] peer1 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer1 # [1601110.085332] peer1 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer1 # [1601110.086158] peer1 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer1 # [1601110.086490] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer1 # [1601110.086843] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer1 # [1601110.087376] peer1 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer1 # [1601110.098153] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer1 # [1601110.207104] peer1 nsncd[224]: Sep 22 20:02:04.630 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 # [1601110.207115] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer1 # [1601110.207152] peer1 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [1601110.207182] peer1 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [1601110.207791] peer1 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer1 # [1601110.208168] peer1 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer1 # [1601110.238176] peer1 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer1 # [1601110.238685] peer1 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer1 # [1601110.238704] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer1 # [1601110.238715] peer1 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> controller # [1601110.330291] controller dbus-broker-launch[236]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> controller # [1601110.330873] controller dbus-broker-launch[236]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> controller # [1601110.330873] controller dbus-broker-launch[236]: Invalid user-name in /nix/store/daj9cl4gyvsmbpx35y5adwr0k5yv07r7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> controller # [1601110.331448] controller systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> controller # [1601110.335173] controller dbus-broker-launch[236]: Ready
container-test-run-dm-wireguard-star> peer1 # [1601110.352802] peer1 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer1 # [1601110.353290] peer1 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer1 # [1601110.353290] peer1 dbus-broker-launch[225]: Invalid user-name in /nix/store/daj9cl4gyvsmbpx35y5adwr0k5yv07r7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer1 # [1601110.353597] peer1 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer1 # [1601110.356982] peer1 dbus-broker-launch[225]: Ready
container-test-run-dm-wireguard-star> controller # [1601110.681036] controller data-mesher[233]: time=2026-09-22T20:02:05.104Z 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 # [1601110.682421] controller data-mesher[233]: time=2026-09-22T20:02:05.105Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf: [/dns/controller.clan/tcp/7946]} {12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq: [/dns/peer1.clan/tcp/7946]} {12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> controller # [1601110.682455] controller data-mesher[233]: time=2026-09-22T20:02:05.105Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> controller # [1601110.683220] controller data-mesher[233]: time=2026-09-22T20:02:05.106Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> controller # [1601110.683290] controller data-mesher[233]: time=2026-09-22T20:02:05.106Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> controller # [1601110.685616] controller data-mesher[233]: time=2026-09-22T20:02:05.108Z level=INFO msg="libp2p host created" peer_id=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf 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::5869:ad4a:6504:5243/tcp/7946]"
container-test-run-dm-wireguard-star> controller # [1601110.685663] controller data-mesher[233]: time=2026-09-22T20:02:05.108Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> controller # [1601110.685663] controller data-mesher[233]: time=2026-09-22T20:02:05.108Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> controller # [1601110.685663] controller data-mesher[233]: time=2026-09-22T20:02:05.108Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> controller # [1601110.685663] controller data-mesher[233]: time=2026-09-22T20:02:05.108Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> controller # [1601110.685730] controller data-mesher[233]: time=2026-09-22T20:02:05.109Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> controller # [1601110.685866] controller data-mesher[233]: time=2026-09-22T20:02:05.109Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> controller # [1601110.685866] controller data-mesher[233]: time=2026-09-22T20:02:05.109Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> controller # [1601110.688666] controller data-mesher[233]: time=2026-09-22T20:02:05.111Z level=INFO msg="peer connected" peer_id=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> controller # [1601110.852017] controller systemd-logind[253]: New seat seat0.
container-test-run-dm-wireguard-star> controller # [1601110.852146] controller systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> controller # [1601110.854216] controller systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> controller # [1601110.861172] controller systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1601110.861249] controller systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> peer1 # [1601110.682566] peer1 data-mesher[222]: time=2026-09-22T20:02:05.105Z 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 # [1601110.683911] peer1 data-mesher[222]: time=2026-09-22T20:02:05.107Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf: [/dns/controller.clan/tcp/7946]} {12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq: [/dns/peer1.clan/tcp/7946]} {12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer1 # [1601110.683911] peer1 data-mesher[222]: time=2026-09-22T20:02:05.107Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer1 # [1601110.684513] peer1 data-mesher[222]: time=2026-09-22T20:02:05.107Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer1 # [1601110.684578] peer1 data-mesher[222]: time=2026-09-22T20:02:05.107Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer1 # [1601110.686856] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="libp2p host created" peer_id=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq 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::2025:c60:174f:9bed/tcp/7946]"
container-test-run-dm-wireguard-star> peer1 # [1601110.686921] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer1 # [1601110.686921] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [1601110.686921] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [1601110.686921] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer1 # [1601110.686984] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer1 # [1601110.687004] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer1 # [1601110.687029] peer1 data-mesher[222]: time=2026-09-22T20:02:05.110Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer1 # [1601110.688860] peer1 data-mesher[222]: time=2026-09-22T20:02:05.112Z level=INFO msg="peer connected" peer_id=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [1601110.831812] peer1 systemd-logind[242]: New seat seat0.
container-test-run-dm-wireguard-star> peer1 # [1601110.831967] peer1 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer1 # [1601110.832929] peer1 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer1 # [1601110.858712] peer1 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1601110.858805] peer1 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller # [1601111.104080] controller systemd-networkd[226]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer1 # [1601111.745088] peer1 systemd-networkd[215]: 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> controller # [1601120.687596] controller data-mesher[233]: time=2026-09-22T20:02:15.110Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> controller # [1601120.687596] controller data-mesher[233]: time=2026-09-22T20:02:15.110Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601120.688170] controller data-mesher[233]: time=2026-09-22T20:02:15.111Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601120.688170] controller data-mesher[233]: time=2026-09-22T20:02:15.111Z level=INFO msg="state exchange complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601120.688215] controller data-mesher[233]: time=2026-09-22T20:02:15.111Z level=INFO msg="received state sync from peer" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601120.688215] controller data-mesher[233]: time=2026-09-22T20:02:15.111Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601120.688252] controller data-mesher[233]: time=2026-09-22T20:02:15.111Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> controller # [1601120.688342] controller data-mesher[233]: time=2026-09-22T20:02:15.111Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> controller # [1601120.688430] controller systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> controller # [1601120.691115] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...
container-test-run-dm-wireguard-star> controller # [1601120.731820] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1601120.731913] controller data-mesher[233]: time=2026-09-22T20:02:15.155Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204
container-test-run-dm-wireguard-star> controller # [1601120.731994] controller dm-wg-star-publish[286]: Status: 204 No Content
container-test-run-dm-wireguard-star> controller # [1601120.733717] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.
container-test-run-dm-wireguard-star> controller # [1601120.733923] controller systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> controller # [1601120.775258] controller dm-wg-star-reconfig[301]: No peer data available yet, skipping
container-test-run-dm-wireguard-star> controller # [1601120.775974] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1601120.789057] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> controller # [1601120.789290] controller systemd[1]: Startup finished in 11.927s.
container-test-run-dm-wireguard-star> peer1 # [1601120.687926] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer1 # [1601120.687926] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601120.688398] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=INFO msg="received state sync from peer" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601120.688398] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601120.688398] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601120.688398] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=INFO msg="state exchange complete" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601120.688398] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer1 # [1601120.688398] peer1 data-mesher[222]: time=2026-09-22T20:02:15.111Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer1 # [1601120.688469] peer1 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer1 # [1601120.689252] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1601120.770314] peer1 data-mesher[222]: time=2026-09-22T20:02:15.193Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 status=204
container-test-run-dm-wireguard-star> peer1 # [1601120.770394] peer1 dm-wg-star-publish[277]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer1 # [1601120.770919] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1601120.772305] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1601120.772385] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1601120.772605] peer1 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer1 # [1601120.831447] peer1 dm-wg-star-reconfig[293]: No controller data available yet, skipping
container-test-run-dm-wireguard-star> peer1 # [1601120.831790] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1601120.831847] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1601120.832029] peer1 systemd[1]: Startup finished in 11.972s.
container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 12.64 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.01 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/v4mkgp543y0bvj1gi240ypfx69ql1qmn-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/v4mkgp543y0bvj1gi240ypfx69ql1qmn-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.00 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> controller # [1601125.690298] controller data-mesher[233]: time=2026-09-22T20:02:20.113Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1601125.690298] controller data-mesher[233]: time=2026-09-22T20:02:20.113Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601125.690860] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601125.691046] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="new file detected" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> controller # [1601125.691046] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=INFO msg="state exchange complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601125.691144] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> controller # [1601125.691144] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=INFO msg="received state sync from peer" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601125.691144] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601125.691144] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 signed_at="2026-09-22 20:02:15.191 +0000 UTC" signed_by="VDU8UNcOwUoZNE7vX+ycOFhGRqE52EtBFIiryhsNGy4=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601125.691144] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=INFO msg="received file request" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1601125.691206] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1601125.691206] controller data-mesher[233]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="new file detected" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> controller # [1601125.692404] controller data-mesher[233]: time=2026-09-22T20:02:20.115Z level=INFO msg="file transfer complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1601125.693212] controller data-mesher[233]: time=2026-09-22T20:02:20.116Z level=INFO msg="download complete" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 signed_at="2026-09-22 20:02:15.191 +0000 UTC" signed_by="VDU8UNcOwUoZNE7vX+ycOFhGRqE52EtBFIiryhsNGy4=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq written=true elapsed=2.121325ms
container-test-run-dm-wireguard-star> controller # [1601125.694163] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1601125.769014] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1601125.769142] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1601125.690713] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1601125.690713] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=INFO msg="received state sync from peer" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601125.690713] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601125.690713] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601125.690713] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="new file detected" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1601125.691273] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1601125.691273] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:15.152 +0000 UTC" signed_by="cREIDwXB4wUgEMCrgbgoubTWdxQ5XZOhUEUDIUfjn9U=" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601125.691273] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601125.691273] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="new file detected" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1601125.691333] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=INFO msg="state exchange complete" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601125.691333] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1601125.691398] peer1 data-mesher[222]: time=2026-09-22T20:02:20.114Z level=INFO msg="received file request" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> peer1 # [1601125.692224] peer1 data-mesher[222]: time=2026-09-22T20:02:20.115Z level=INFO msg="file transfer complete" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> peer1 # [1601125.693191] peer1 data-mesher[222]: time=2026-09-22T20:02:20.116Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:15.152 +0000 UTC" signed_by="cREIDwXB4wUgEMCrgbgoubTWdxQ5XZOhUEUDIUfjn9U=" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf written=true elapsed=2.387787ms
container-test-run-dm-wireguard-star> peer1 # [1601125.694155] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1601125.769589] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1601125.769653] 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.02 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::5869:ad4a:6504:5243
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::5869:ad4a:6504:5243, in 0.00 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2025:0c60:174f:9bed
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2025:0c60:174f:9bed, in 0.00 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 739)
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 # [1601126.817279] peer2 systemd-journald[99]: Journal started
container-test-run-dm-wireguard-star> peer2 # [1601126.817309] peer2 systemd-journald[99]: Runtime Journal (/run/log/journal/0a2da3c0840a488c97fea8e09217ee9e) is 8M, max 3.7G, 3.7G free.
container-test-run-dm-wireguard-star> peer2 # [1601126.821992] peer2 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> peer2 # [1601126.828753] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> peer2 # [1601126.836607] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer2 # [1601126.837031] peer2 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> peer2 # [1601126.837354] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> peer2 # [1601126.861353] peer2 systemd-journald[99]: Time spent on flushing to /var/log/journal/0a2da3c0840a488c97fea8e09217ee9e is 1.017ms for 7 entries.
container-test-run-dm-wireguard-star> peer2 # [1601126.861353] peer2 systemd-journald[99]: System Journal (/var/log/journal/0a2da3c0840a488c97fea8e09217ee9e) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer2 # [1601126.869733] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer2 # [1601126.869950] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer2 # [1601126.870720] peer2 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [1601126.870766] peer2 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [1601126.871220] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer2 # [1601126.871247] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [1601126.871692] peer2 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer2 # [1601126.872152] peer2 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer2 # [1601126.872173] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer2 # [1601126.881466] peer2 systemd-tmpfiles[177]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1601126.881608] peer2 systemd-tmpfiles[177]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1601126.881709] peer2 systemd-tmpfiles[177]: fchmod() of /var/log/journal/0a2da3c0840a488c97fea8e09217ee9e failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1601126.881860] peer2 systemd-tmpfiles[177]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1601126.883364] peer2 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer2 # [1601126.883825] peer2 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer2 # [1601126.884219] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer2 # [1601126.889261] peer2 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer2 # [1601126.891352] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer2 # [1601126.897117] peer2 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer2 # [1601126.897729] peer2 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer2 # [1601126.903546] peer2 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer2 # [1601126.905215] peer2 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer2 # [1601126.905281] peer2 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer2 # [1601126.905449] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer2 # [1601126.906036] peer2 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer2 # [1601127.348590] peer2 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1601127.348651] peer2 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1601127.356413] peer2 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> peer2 # [1601127.356555] peer2 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> peer2 # [1601127.356612] peer2 systemd-networkd[217]: lo: Link UP
container-test-run-dm-wireguard-star> peer2 # [1601127.356614] peer2 systemd-networkd[217]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1601127.356743] peer2 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer2 # [1601127.356965] peer2 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer2 # [1601127.357321] peer2 systemd-networkd[217]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer2 # [1601127.357460] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer2 # [1601127.357491] peer2 systemd-networkd[217]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer2 # [1601127.357605] peer2 systemd-networkd[217]: eth1: Link UP
container-test-run-dm-wireguard-star> peer2 # [1601127.357706] peer2 systemd-networkd[217]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1601127.376276] peer2 systemd-networkd[217]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer2 # [1601127.376279] peer2 systemd-networkd[217]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1601127.380246] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer2 # [1601127.696512] peer2 systemd-resolved[137]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer2 # [1601127.696518] peer2 systemd-resolved[137]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer2 # [1601127.696521] peer2 systemd-resolved[137]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer2 # [1601127.696537] peer2 systemd-resolved[137]: 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 # [1601127.707142] peer2 systemd-resolved[137]: Using system hostname 'peer2'.
container-test-run-dm-wireguard-star> peer2 # [1601127.708131] peer2 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer2 # [1601127.708175] peer2 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer2 # [1601127.708204] peer2 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer2 # [1601127.708252] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1601127.708271] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer2 # [1601127.708287] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [1601127.708303] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer2 # [1601127.708314] peer2 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer2 # [1601127.708335] peer2 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer2 # [1601127.708400] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer2 # [1601127.708461] peer2 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer2 # [1601127.708526] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer2 # [1601127.708535] peer2 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer2 # [1601127.708556] peer2 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer2 # [1601127.709173] peer2 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer2 # [1601127.709476] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer2 # [1601127.709811] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer2 # [1601127.710402] peer2 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer2 # [1601127.735583] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer2 # [1601127.813631] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1601127.829501] peer2 nsncd[224]: Sep 22 20:02:22.252 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 # [1601127.829485] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer2 # [1601127.829510] peer2 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [1601127.829537] peer2 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [1601127.830457] peer2 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer2 # [1601127.830808] peer2 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer2 # [1601127.850245] peer2 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer2 # [1601127.850628] peer2 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer2 # [1601127.850647] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer2 # [1601127.850656] peer2 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer2 # [1601127.924251] peer2 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer2 # [1601127.924663] peer2 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer2 # [1601127.924663] peer2 dbus-broker-launch[225]: Invalid user-name in /nix/store/daj9cl4gyvsmbpx35y5adwr0k5yv07r7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer2 # [1601127.924950] peer2 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer2 # [1601127.928677] peer2 dbus-broker-launch[225]: Ready
container-test-run-dm-wireguard-star> controller # [1601128.209374] controller data-mesher[233]: time=2026-09-22T20:02:22.632Z level=INFO msg="peer connected" peer_id=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1601128.202819] peer2 data-mesher[222]: time=2026-09-22T20:02:22.626Z 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 # [1601128.203119] peer2 data-mesher[222]: time=2026-09-22T20:02:22.626Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf: [/dns/controller.clan/tcp/7946]} {12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq: [/dns/peer1.clan/tcp/7946]} {12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> peer2 # [1601128.203135] peer2 data-mesher[222]: time=2026-09-22T20:02:22.626Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer2 # [1601128.203942] peer2 data-mesher[222]: time=2026-09-22T20:02:22.627Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer2 # [1601128.204039] peer2 data-mesher[222]: time=2026-09-22T20:02:22.627Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer2 # [1601128.207198] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="libp2p host created" peer_id=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB 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::a86c:9d2:4d89:7c9c/tcp/7946]"
container-test-run-dm-wireguard-star> peer2 # [1601128.207222] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [1601128.207222] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer2 # [1601128.207222] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [1601128.207222] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer2 # [1601128.207312] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer2 # [1601128.207358] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer2 # [1601128.207383] peer2 data-mesher[222]: time=2026-09-22T20:02:22.630Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer2 # [1601128.209186] peer2 data-mesher[222]: time=2026-09-22T20:02:22.632Z level=INFO msg="peer connected" peer_id=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1601128.211135] peer2 data-mesher[222]: time=2026-09-22T20:02:22.634Z level=INFO msg="peer connected" peer_id=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1601128.351142] peer2 systemd-logind[242]: New seat seat0.
container-test-run-dm-wireguard-star> peer2 # [1601128.351260] peer2 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer2 # [1601128.352015] peer2 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer2 # [1601128.375045] peer2 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1601128.375162] peer2 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> peer1 # [1601128.211312] peer1 data-mesher[222]: time=2026-09-22T20:02:22.634Z level=INFO msg="peer connected" peer_id=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1601129.152400] peer2 systemd-networkd[217]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> controller # [1601130.692136] controller data-mesher[233]: time=2026-09-22T20:02:25.115Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1601130.692136] controller data-mesher[233]: time=2026-09-22T20:02:25.115Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601130.692913] controller data-mesher[233]: time=2026-09-22T20:02:25.116Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601130.693017] controller data-mesher[233]: time=2026-09-22T20:02:25.116Z level=INFO msg="state exchange complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601130.693077] controller data-mesher[233]: time=2026-09-22T20:02:25.116Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [1601130.692659] peer2 data-mesher[222]: time=2026-09-22T20:02:25.115Z level=INFO msg="received state sync from peer" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer2 # [1601130.692659] peer2 data-mesher[222]: time=2026-09-22T20:02:25.115Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer2 # [1601130.692999] peer2 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=DEBUG msg="new file detected" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> peer2 # [1601130.692999] peer2 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=DEBUG msg="new file detected" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [1601130.692999] peer2 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> peer2 # [1601130.692999] peer2 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [1601130.692999] peer2 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:15.152 +0000 UTC" signed_by="cREIDwXB4wUgEMCrgbgoubTWdxQ5XZOhUEUDIUfjn9U=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer2 # [1601130.692999] peer2 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 signed_at="2026-09-22 20:02:15.191 +0000 UTC" signed_by="VDU8UNcOwUoZNE7vX+ycOFhGRqE52EtBFIiryhsNGy4=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer2 # [1601130.695031] peer2 data-mesher[222]: time=2026-09-22T20:02:25.118Z level=INFO msg="download complete" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4 signed_at="2026-09-22 20:02:15.191 +0000 UTC" signed_by="VDU8UNcOwUoZNE7vX+ycOFhGRqE52EtBFIiryhsNGy4=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq written=true elapsed=2.167121ms
container-test-run-dm-wireguard-star> peer2 # [1601130.695531] peer2 data-mesher[222]: time=2026-09-22T20:02:25.118Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 20:02:15.152 +0000 UTC" signed_by="cREIDwXB4wUgEMCrgbgoubTWdxQ5XZOhUEUDIUfjn9U=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq written=true elapsed=2.699835ms
container-test-run-dm-wireguard-star> peer2 # [1601130.696057] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1601130.772628] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1601130.772749] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1601130.692136] peer1 data-mesher[222]: time=2026-09-22T20:02:25.115Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1601130.692136] peer1 data-mesher[222]: time=2026-09-22T20:02:25.115Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601130.692650] peer1 data-mesher[222]: time=2026-09-22T20:02:25.115Z level=INFO msg="received state sync from peer" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601130.692650] peer1 data-mesher[222]: time=2026-09-22T20:02:25.115Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601130.692900] peer1 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="merging remote state" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> peer1 # [1601130.692900] peer1 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="state exchange complete" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601130.692959] peer1 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1601130.693206] peer1 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="received file request" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1601130.693238] peer1 data-mesher[222]: time=2026-09-22T20:02:25.116Z level=INFO msg="received file request" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> peer1 # [1601130.694363] peer1 data-mesher[222]: time=2026-09-22T20:02:25.117Z level=INFO msg="file transfer complete" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/VDU8UNcOwUoZNE7vX-ycOFhGRqE52EtBFIiryhsNGy4
container-test-run-dm-wireguard-star> peer1 # [1601130.695064] peer1 data-mesher[222]: time=2026-09-22T20:02:25.118Z level=INFO msg="file transfer complete" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1601135.693370] controller data-mesher[233]: time=2026-09-22T20:02:30.116Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1601135.693370] controller data-mesher[233]: time=2026-09-22T20:02:30.116Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB timeout=5s
container-test-run-dm-wireguard-star> controller # [1601135.693837] controller data-mesher[233]: time=2026-09-22T20:02:30.117Z level=INFO msg="received state sync from peer" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601135.693837] controller data-mesher[233]: time=2026-09-22T20:02:30.117Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601135.694225] controller data-mesher[233]: time=2026-09-22T20:02:30.117Z level=INFO msg="merging remote state" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> controller # [1601135.694301] controller data-mesher[233]: time=2026-09-22T20:02:30.117Z level=INFO msg="state exchange complete" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB timeout=5s
container-test-run-dm-wireguard-star> controller # [1601135.694324] controller data-mesher[233]: time=2026-09-22T20:02:30.117Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [1601135.693953] peer2 data-mesher[222]: time=2026-09-22T20:02:30.117Z level=INFO msg="received state sync from peer" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer2 # [1601135.693953] peer2 data-mesher[222]: time=2026-09-22T20:02:30.117Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601135.693375] peer1 data-mesher[222]: time=2026-09-22T20:02:30.116Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1601135.693375] peer1 data-mesher[222]: time=2026-09-22T20:02:30.116Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601135.693975] peer1 data-mesher[222]: time=2026-09-22T20:02:30.117Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601135.694056] peer1 data-mesher[222]: time=2026-09-22T20:02:30.117Z level=INFO msg="state exchange complete" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601135.694084] peer1 data-mesher[222]: time=2026-09-22T20:02:30.117Z 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: (finished: waiting for unit data-mesher.service, in 12.15 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/v4mkgp543y0bvj1gi240ypfx69ql1qmn-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/v4mkgp543y0bvj1gi240ypfx69ql1qmn-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> peer2 # [1601138.208273] peer2 data-mesher[222]: time=2026-09-22T20:02:32.631Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer2 # [1601138.208273] peer2 data-mesher[222]: time=2026-09-22T20:02:32.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1601138.209021] peer2 data-mesher[222]: time=2026-09-22T20:02:32.632Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer2 # [1601138.209115] peer2 data-mesher[222]: time=2026-09-22T20:02:32.632Z level=INFO msg="state exchange complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1601138.209140] peer2 data-mesher[222]: time=2026-09-22T20:02:32.632Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer2 # [1601138.209187] peer2 data-mesher[222]: time=2026-09-22T20:02:32.632Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer2 # [1601138.209305] peer2 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer2 # [1601138.210122] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1601138.289445] peer2 data-mesher[222]: time=2026-09-22T20:02:32.712Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw status=204
container-test-run-dm-wireguard-star> peer2 # [1601138.289492] peer2 dm-wg-star-publish[287]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer2 # [1601138.290381] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1601138.291242] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1601138.291343] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1601138.291602] peer2 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer2 # [1601138.357890] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1601138.358008] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1601138.358155] peer2 systemd[1]: Startup finished in 11.794s.
container-test-run-dm-wireguard-star> peer1 # [1601138.208761] peer1 data-mesher[222]: time=2026-09-22T20:02:32.632Z level=INFO msg="received state sync from peer" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> peer1 # [1601138.208761] peer1 data-mesher[222]: time=2026-09-22T20:02:32.632Z level=INFO msg="merging remote state" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> controller # [1601140.694602] controller data-mesher[233]: time=2026-09-22T20:02:35.117Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1601140.694602] controller data-mesher[233]: time=2026-09-22T20:02:35.117Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601140.695021] controller data-mesher[233]: time=2026-09-22T20:02:35.118Z level=INFO msg="received state sync from peer" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601140.695021] controller data-mesher[233]: time=2026-09-22T20:02:35.118Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601140.695129] controller data-mesher[233]: time=2026-09-22T20:02:35.118Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601140.695184] controller data-mesher[233]: time=2026-09-22T20:02:35.118Z level=INFO msg="state exchange complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601140.695217] controller data-mesher[233]: time=2026-09-22T20:02:35.118Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1601140.694514] peer1 data-mesher[222]: time=2026-09-22T20:02:35.117Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1601140.694514] peer1 data-mesher[222]: time=2026-09-22T20:02:35.117Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601140.695016] peer1 data-mesher[222]: time=2026-09-22T20:02:35.118Z level=INFO msg="received state sync from peer" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601140.695016] peer1 data-mesher[222]: time=2026-09-22T20:02:35.118Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601140.695016] peer1 data-mesher[222]: time=2026-09-22T20:02:35.118Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601140.695125] peer1 data-mesher[222]: time=2026-09-22T20:02:35.118Z level=INFO msg="state exchange complete" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601140.695166] peer1 data-mesher[222]: time=2026-09-22T20:02:35.118Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [1601143.209431] peer2 data-mesher[222]: time=2026-09-22T20:02:37.632Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-dm-wireguard-star> peer2 # [1601143.209431] peer2 data-mesher[222]: time=2026-09-22T20:02:37.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1601143.210306] peer2 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer2 # [1601143.210406] peer2 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=INFO msg="state exchange complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1601143.210406] peer2 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=INFO msg="received file request" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
container-test-run-dm-wireguard-star> peer2 # [1601143.210406] peer2 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [1601143.211523] peer2 data-mesher[222]: time=2026-09-22T20:02:37.634Z level=INFO msg="file transfer complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
container-test-run-dm-wireguard-star> peer1 # [1601143.209928] peer1 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=INFO msg="received state sync from peer" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> peer1 # [1601143.209928] peer1 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=INFO msg="merging remote state" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> peer1 # [1601143.210373] peer1 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=DEBUG msg="new file detected" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
container-test-run-dm-wireguard-star> peer1 # [1601143.210373] peer1 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
container-test-run-dm-wireguard-star> peer1 # [1601143.210373] peer1 data-mesher[222]: time=2026-09-22T20:02:37.633Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw signed_at="2026-09-22 20:02:32.71 +0000 UTC" signed_by="Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw=" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> peer1 # [1601143.212062] peer1 data-mesher[222]: time=2026-09-22T20:02:37.635Z level=INFO msg="download complete" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw signed_at="2026-09-22 20:02:32.71 +0000 UTC" signed_by="Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw=" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB written=true elapsed=1.891984ms
container-test-run-dm-wireguard-star> peer1 # [1601143.213252] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1601143.291595] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1601143.291720] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> controller # [1601145.695612] controller data-mesher[233]: time=2026-09-22T20:02:40.118Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1601145.695612] controller data-mesher[233]: time=2026-09-22T20:02:40.118Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601145.696190] controller data-mesher[233]: time=2026-09-22T20:02:40.119Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601145.696284] controller data-mesher[233]: time=2026-09-22T20:02:40.119Z level=DEBUG msg="new file detected" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
container-test-run-dm-wireguard-star> controller # [1601145.696374] controller data-mesher[233]: time=2026-09-22T20:02:40.119Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
container-test-run-dm-wireguard-star> controller # [1601145.696374] controller data-mesher[233]: time=2026-09-22T20:02:40.119Z level=INFO msg="state exchange complete" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq timeout=5s
container-test-run-dm-wireguard-star> controller # [1601145.696428] controller data-mesher[233]: time=2026-09-22T20:02:40.119Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1601145.696428] controller data-mesher[233]: time=2026-09-22T20:02:40.119Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw signed_at="2026-09-22 20:02:32.71 +0000 UTC" signed_by="Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> controller # [1601145.698081] controller data-mesher[233]: time=2026-09-22T20:02:40.121Z level=INFO msg="download complete" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw signed_at="2026-09-22 20:02:32.71 +0000 UTC" signed_by="Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw=" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq written=true elapsed=1.694741ms
container-test-run-dm-wireguard-star> controller # [1601145.699337] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1601145.772221] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1601145.772354] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1601145.696291] peer2 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=INFO msg="received state sync from peer" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer2 # [1601145.696291] peer2 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=INFO msg="merging remote state" peer=12D3KooWFV5Vo3u1NDMtxq4BG7gSTza5Qa9VdqZBxkfkCG2QG4zq
container-test-run-dm-wireguard-star> peer1 # [1601145.695919] peer1 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1601145.695919] peer1 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601145.695919] peer1 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=INFO msg="received state sync from peer" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601145.695919] peer1 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=INFO msg="merging remote state" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf
container-test-run-dm-wireguard-star> peer1 # [1601145.696574] peer1 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=INFO msg="received file request" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
container-test-run-dm-wireguard-star> peer1 # [1601145.696605] peer1 data-mesher[222]: time=2026-09-22T20:02:40.119Z level=INFO msg="merging remote state" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB
container-test-run-dm-wireguard-star> peer1 # [1601145.696857] peer1 data-mesher[222]: time=2026-09-22T20:02:40.120Z level=INFO msg="state exchange complete" peer=12D3KooWE1KyBMCiYHQmw9yb5NdjxwSNrZb8Gmf18vZ9ZpjEkPvB timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1601145.696905] peer1 data-mesher[222]: time=2026-09-22T20:02:40.120Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1601145.697032] peer1 data-mesher[222]: time=2026-09-22T20:02:40.120Z level=INFO msg="file transfer complete" peer=12D3KooWKktANeHJQ32a52JkMUfcrNjwgMWzHzNGvYx3qdEA4Tdf network="8I73NX+mvsflqW2diPk4BODw3xEiqiP5lJIh9De/G7w=" name=dm_wg_star_wg_star/Pj2mVRAeHIVIgf8f1TkE0Py3Qrh6mtU9JWDQEvwDsaw
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 8.06 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::5869:ad4a:6504:5243
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::5869:ad4a:6504:5243, in 0.25 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a86c:09d2:4d89:7c9c
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a86c:09d2:4d89:7c9c, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a86c:09d2:4d89:7c9c
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::a86c:09d2:4d89:7c9c, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2025:0c60:174f:9bed
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2025:0c60:174f:9bed, in 0.00 seconds)
container-test-run-dm-wireguard-star> (finished: run the VM test script, in 38.20 seconds)
container-test-run-dm-wireguard-star> test script finished in 38.21s
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 739)
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.34 seconds)

post-build step Upload to niks3: ok
time=2026-09-22T20:02:41.827Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-09-22T20:02:42.675Z level=INFO msg="Uploading 1 narinfos"
time=2026-09-22T20:02:44.068Z level=INFO msg="Upload complete. (2.299s)"