these 24 derivations will be built:
  /nix/store/an51m1l10a75k1hqnl2apcsqzmxfl3q0-system-path.drv
  /nix/store/x23vvjzlk23nyfsq1qwbqilrnfi5v6wm-dbus-1.drv
  /nix/store/vy6dypqncq0d7s0hka3k29qrc5g6642b-X-Restart-Triggers-dbus-broker.drv
  /nix/store/hhmjr41xh6lkzpc87jjrmmc0g7wz3nlq-unit-dbus-broker.service.drv
  /nix/store/174jcrpmxqjc94h8l6zh535z8q2p10gs-user-units.drv
  /nix/store/c3jp6s82l87ik521bly7w67xx3v9j6dy-unit-dbus-broker.service.drv
  /nix/store/zpk22v0kpwvi22rgysrbamkg8z9rzdjx-system-units.drv
  /nix/store/8nww65ahw74r6f3ajz0lfqm7n7ywang8-etc.drv
  /nix/store/carpx6dymgk5mcm6rhqa105whpr002ka-activate.drv
  /nix/store/prf8nvdv607pi1gq2s7xv6icz8x2d8vk-nixos-system-peer1-test.drv
  /nix/store/bhbxfmjdy0h20s0vgk2m10inbl8vfixn-run-peer1-nspawn.drv
  /nix/store/xansad3dskr7jp89hz2995arj3zmb0w3-system-units.drv
  /nix/store/bg8dmn7kdnwv48sw1y4395vkl6h9rcms-etc.drv
  /nix/store/hxpj0nqk19m2zvp6mxhx2l15hvz7s3j8-activate.drv
  /nix/store/fn8g70r3828angf1ny2f3h3nfzy2mkna-nixos-system-peer2-test.drv
  /nix/store/mxnwyfap0dc572rd26mfl6r7w6addqfb-run-peer2-nspawn.drv
  /nix/store/gv7xiz4fzadsc8pixgim2ns9y3mdzdh5-system-units.drv
  /nix/store/yvqy6pkin8cfw93sc8yxvxkx59r5s553-etc.drv
  /nix/store/pflhgwpnr7f7lr1f8whfxbij6bh9q8zx-activate.drv
  /nix/store/fl7f0insrz12d5a5chvl5jzr00p5ksfz-nixos-system-controller-test.drv
  /nix/store/wi9jcjqzvs4p0bb91wn3x03la6ac7m01-run-controller-nspawn.drv
  /nix/store/akzbqyfkva0n0adkqsi8lw363pan52np-driverConfiguration.json.drv
  /nix/store/6j73icdklch8g53vzp012m7znk88avbc-nixos-test-driver-dm-wireguard-star.drv
  /nix/store/wwawzdcy70vyg652n2ks1ym5f1p924x1-container-test-run-dm-wireguard-star.drv
building '/nix/store/an51m1l10a75k1hqnl2apcsqzmxfl3q0-system-path.drv'
system-path> structuredAttrs is enabled
system-path> created 1731 symlinks in user environment
building '/nix/store/x23vvjzlk23nyfsq1qwbqilrnfi5v6wm-dbus-1.drv'
building '/nix/store/vy6dypqncq0d7s0hka3k29qrc5g6642b-X-Restart-Triggers-dbus-broker.drv'
building '/nix/store/c3jp6s82l87ik521bly7w67xx3v9j6dy-unit-dbus-broker.service.drv'
building '/nix/store/hhmjr41xh6lkzpc87jjrmmc0g7wz3nlq-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/gv7xiz4fzadsc8pixgim2ns9y3mdzdh5-system-units.drv'
building '/nix/store/xansad3dskr7jp89hz2995arj3zmb0w3-system-units.drv'
building '/nix/store/zpk22v0kpwvi22rgysrbamkg8z9rzdjx-system-units.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/174jcrpmxqjc94h8l6zh535z8q2p10gs-user-units.drv'
building '/nix/store/yvqy6pkin8cfw93sc8yxvxkx59r5s553-etc.drv'
building '/nix/store/8nww65ahw74r6f3ajz0lfqm7n7ywang8-etc.drv'
building '/nix/store/bg8dmn7kdnwv48sw1y4395vkl6h9rcms-etc.drv'
building '/nix/store/pflhgwpnr7f7lr1f8whfxbij6bh9q8zx-activate.drv'
building '/nix/store/fl7f0insrz12d5a5chvl5jzr00p5ksfz-nixos-system-controller-test.drv'
building '/nix/store/carpx6dymgk5mcm6rhqa105whpr002ka-activate.drv'
building '/nix/store/hxpj0nqk19m2zvp6mxhx2l15hvz7s3j8-activate.drv'
nixos-system-controller-test> structuredAttrs is enabled
building '/nix/store/wi9jcjqzvs4p0bb91wn3x03la6ac7m01-run-controller-nspawn.drv'
building '/nix/store/prf8nvdv607pi1gq2s7xv6icz8x2d8vk-nixos-system-peer1-test.drv'
building '/nix/store/fn8g70r3828angf1ny2f3h3nfzy2mkna-nixos-system-peer2-test.drv'
nixos-system-peer1-test> structuredAttrs is enabled
nixos-system-peer2-test> structuredAttrs is enabled
building '/nix/store/bhbxfmjdy0h20s0vgk2m10inbl8vfixn-run-peer1-nspawn.drv'
building '/nix/store/mxnwyfap0dc572rd26mfl6r7w6addqfb-run-peer2-nspawn.drv'
building '/nix/store/akzbqyfkva0n0adkqsi8lw363pan52np-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/6j73icdklch8g53vzp012m7znk88avbc-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/wwawzdcy70vyg652n2ks1ym5f1p924x1-container-test-run-dm-wireguard-star.drv' on 'ssh-ng://builder@build-x86-01.clan.lol'
building '/nix/store/wwawzdcy70vyg652n2ks1ym5f1p924x1-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(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> 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> 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> 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> peer1 # [46080.758659] peer1 systemd-journald[96]: Journal started
container-test-run-dm-wireguard-star> peer1 # [46080.758700] peer1 systemd-journald[96]: Runtime Journal (/run/log/journal/1a6e676fd80f4e499df054e587524a98) is 8M, max 3.7G, 3.7G free.
container-test-run-dm-wireguard-star> peer1 # [46080.761635] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [46080.752494] controller systemd-journald[105]: Journal started
container-test-run-dm-wireguard-star> controller # [46080.752527] controller systemd-journald[105]: Runtime Journal (/run/log/journal/5d3df20210384b8e846a0c0386811ce3) is 8M, max 3.7G, 3.7G free.
container-test-run-dm-wireguard-star> controller # [46080.754462] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [46080.760586] controller systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> controller # [46080.761017] controller systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [46080.761583] controller systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [46080.766176] controller systemd-journald[105]: Time spent on flushing to /var/log/journal/5d3df20210384b8e846a0c0386811ce3 is 1.050ms for 6 entries.
container-test-run-dm-wireguard-star> controller # [46080.766176] controller systemd-journald[105]: System Journal (/var/log/journal/5d3df20210384b8e846a0c0386811ce3) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer1 # [46080.767156] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer1 # [46080.767675] peer1 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [46080.770234] controller systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer1 # [46080.768158] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [46080.770402] controller systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [46080.771753] peer1 systemd-journald[96]: Time spent on flushing to /var/log/journal/1a6e676fd80f4e499df054e587524a98 is 1.472ms for 6 entries.
container-test-run-dm-wireguard-star> controller # [46080.770461] controller systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [46080.771753] peer1 systemd-journald[96]: System Journal (/var/log/journal/1a6e676fd80f4e499df054e587524a98) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> controller # [46080.771023] controller systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer1 # [46080.778106] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> controller # [46080.771060] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [46080.778341] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> controller # [46080.771633] controller systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer1 # [46080.779171] peer1 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [46080.779228] peer1 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> controller # [46080.771653] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer1 # [46080.779718] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> controller # [46080.771904] controller systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer1 # [46080.779747] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [46080.772889] controller systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer1 # [46080.780239] peer1 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> controller # [46080.784030] controller systemd-tmpfiles[145]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [46080.780626] peer1 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> controller # [46080.784196] controller systemd-tmpfiles[145]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [46080.780640] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> controller # [46080.784301] controller systemd-tmpfiles[145]: fchmod() of /var/log/journal/5d3df20210384b8e846a0c0386811ce3 failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [46080.793005] peer1 systemd-tmpfiles[137]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [46080.784454] controller systemd-tmpfiles[145]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [46080.793197] peer1 systemd-tmpfiles[137]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [46080.785369] controller systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> controller # [46080.785991] controller systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer1 # [46080.793313] peer1 systemd-tmpfiles[137]: fchmod() of /var/log/journal/1a6e676fd80f4e499df054e587524a98 failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [46080.786358] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> controller # [46080.792383] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> controller # [46080.803248] controller systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> controller # [46080.804275] controller systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> controller # [46080.811266] controller systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> controller # [46080.842940] controller systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> controller # [46080.852413] controller systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> controller # [46080.852481] controller systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> controller # [46080.852640] controller systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> controller # [46080.853299] controller systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> controller # [46081.187812] controller systemd-networkd[225]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [46081.187896] controller systemd-networkd[225]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [46081.194627] controller systemd-networkd[225]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> controller # [46081.194804] controller systemd-networkd[225]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> controller # [46081.194898] controller systemd-networkd[225]: lo: Link UP
container-test-run-dm-wireguard-star> controller # [46081.194901] controller systemd-networkd[225]: lo: Gained carrier
container-test-run-dm-wireguard-star> controller # [46081.195055] controller systemd-networkd[225]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> controller # [46081.195331] controller systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> controller # [46081.208246] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> controller # [46081.208733] controller systemd-networkd[225]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> controller # [46081.209032] controller systemd-networkd[225]: wg-star: netdev ready
container-test-run-dm-wireguard-star> controller # [46081.209149] controller systemd-networkd[225]: eth1: Link UP
container-test-run-dm-wireguard-star> controller # [46081.209279] controller systemd-networkd[225]: eth1: Gained carrier
container-test-run-dm-wireguard-star> controller # [46081.221719] controller systemd-networkd[225]: wg-star: Link UP
container-test-run-dm-wireguard-star> controller # [46081.221724] controller systemd-networkd[225]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> controller # [46081.222874] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer1 # [46080.793498] peer1 systemd-tmpfiles[137]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [46080.794847] peer1 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer1 # [46080.795747] peer1 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer1 # [46080.796313] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer1 # [46080.804304] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer1 # [46080.812314] peer1 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer1 # [46080.813049] peer1 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer1 # [46080.817985] peer1 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [46080.842963] peer1 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer1 # [46080.874119] peer1 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer1 # [46080.874275] peer1 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer1 # [46080.874460] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer1 # [46080.875211] peer1 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer1 # [46081.183031] peer1 systemd-networkd[214]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [46081.183113] peer1 systemd-networkd[214]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [46081.189673] peer1 systemd-networkd[214]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> peer1 # [46081.189842] peer1 systemd-networkd[214]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> peer1 # [46081.189926] peer1 systemd-networkd[214]: lo: Link UP
container-test-run-dm-wireguard-star> peer1 # [46081.189931] peer1 systemd-networkd[214]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [46081.190076] peer1 systemd-networkd[214]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer1 # [46081.190361] peer1 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer1 # [46081.190768] peer1 systemd-networkd[214]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer1 # [46081.190975] peer1 systemd-networkd[214]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer1 # [46081.191065] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer1 # [46081.191104] peer1 systemd-networkd[214]: eth1: Link UP
container-test-run-dm-wireguard-star> peer1 # [46081.191261] peer1 systemd-networkd[214]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [46081.208755] peer1 systemd-networkd[214]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer1 # [46081.208757] peer1 systemd-networkd[214]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [46081.213243] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer1 # [46081.311926] peer1 systemd-resolved[119]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer1 # [46081.311936] peer1 systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer1 # [46081.311939] peer1 systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer1 # [46081.311955] peer1 systemd-resolved[119]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
container-test-run-dm-wireguard-star> peer1 # [46081.322840] peer1 systemd-resolved[119]: Using system hostname 'peer1'.
container-test-run-dm-wireguard-star> peer1 # [46081.323968] peer1 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer1 # [46081.324060] peer1 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer1 # [46081.324109] peer1 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer1 # [46081.324167] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [46081.324190] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer1 # [46081.324207] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [46081.324218] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer1 # [46081.324228] peer1 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer1 # [46081.324249] peer1 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer1 # [46081.324334] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer1 # [46081.324420] peer1 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer1 # [46081.324509] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer1 # [46081.324526] peer1 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer1 # [46081.324553] peer1 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer1 # [46081.325376] peer1 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer1 # [46081.325876] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer1 # [46081.326355] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer1 # [46081.327068] peer1 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer1 # [46081.357432] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer1 # [46081.426491] peer1 nsncd[221]: Sep 04 20:04:55.849 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-dm-wireguard-star> peer1 # [46081.426514] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer1 # [46081.426541] peer1 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [46081.426569] peer1 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> controller # [46081.312054] controller systemd-resolved[128]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer1 # [46081.438138] peer1 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> controller # [46081.312062] controller systemd-resolved[128]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer1 # [46081.438532] peer1 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> controller # [46081.312067] controller systemd-resolved[128]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> controller # [46081.312084] controller systemd-resolved[128]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
container-test-run-dm-wireguard-star> controller # [46081.323963] controller systemd-resolved[128]: Using system hostname 'controller'.
container-test-run-dm-wireguard-star> controller # [46081.325015] controller systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> controller # [46081.325071] controller systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> controller # [46081.325107] controller systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> controller # [46081.325157] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.
container-test-run-dm-wireguard-star> controller # [46081.325174] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [46081.325188] controller systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> controller # [46081.325201] controller systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> controller # [46081.325218] controller systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> controller # [46081.325292] controller systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> controller # [46081.325349] controller systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> controller # [46081.325414] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> controller # [46081.325423] controller systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> controller # [46081.325444] controller systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> controller # [46081.326172] controller systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> controller # [46081.326507] controller systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> controller # [46081.326888] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> controller # [46081.327513] controller systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> controller # [46081.359118] controller systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> controller # [46081.417561] controller nsncd[232]: Sep 04 20:04:55.840 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-dm-wireguard-star> controller # [46081.417612] controller systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> controller # [46081.417656] controller systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> controller # [46081.417695] controller systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> controller # [46081.418634] controller systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> controller # [46081.419125] controller systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> controller # [46081.442372] controller systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> controller # [46081.443268] controller systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> controller # [46081.443302] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> controller # [46081.443315] controller systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> controller # [46081.490295] controller dbus-broker-launch[233]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> controller # [46081.490794] controller dbus-broker-launch[233]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> controller # [46081.490794] controller dbus-broker-launch[233]: Invalid user-name in /nix/store/2vdmzcm94vw773ig833bsswy8c0krm4j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> controller # [46081.491167] controller systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> controller # [46081.494932] controller dbus-broker-launch[233]: Ready
container-test-run-dm-wireguard-star> controller # [46081.688291] controller data-mesher[230]: time=2026-09-04T20:04:56.111Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-dm-wireguard-star> controller # [46081.688653] controller data-mesher[230]: time=2026-09-04T20:04:56.111Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z: [/dns/controller.clan/tcp/7946]} {12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi: [/dns/peer1.clan/tcp/7946]} {12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> controller # [46081.688653] controller data-mesher[230]: time=2026-09-04T20:04:56.111Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> controller # [46081.689478] controller data-mesher[230]: time=2026-09-04T20:04:56.112Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> controller # [46081.689536] controller data-mesher[230]: time=2026-09-04T20:04:56.112Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="libp2p host created" peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.1/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::1/tcp/7946 /ip6/fda1:5c8::9053:b839:432e:800a/tcp/7946]"
container-test-run-dm-wireguard-star> controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> controller # [46081.693176] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> controller # [46081.693340] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> controller # [46081.693395] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer1 # [46081.443681] peer1 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer1 # [46081.444213] peer1 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer1 # [46081.444234] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer1 # [46081.444244] peer1 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer1 # [46081.483808] peer1 dbus-broker-launch[222]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer1 # [46081.484220] peer1 dbus-broker-launch[222]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer1 # [46081.484220] peer1 dbus-broker-launch[222]: Invalid user-name in /nix/store/2vdmzcm94vw773ig833bsswy8c0krm4j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer1 # [46081.484443] peer1 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer1 # [46081.488059] peer1 dbus-broker-launch[222]: Ready
container-test-run-dm-wireguard-star> peer1 # [46081.698566] peer1 data-mesher[219]: time=2026-09-04T20:04:56.121Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-dm-wireguard-star> peer1 # [46081.698846] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z: [/dns/controller.clan/tcp/7946]} {12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi: [/dns/peer1.clan/tcp/7946]} {12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer1 # [46081.698872] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer1 # [46081.699564] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer1 # [46081.699635] peer1 data-mesher[219]: time=2026-09-04T20:04:56.122Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer1 # [46081.702594] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="libp2p host created" peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946 /ip6/fda1:5c8::11ba:c4d4:8af6:629f/tcp/7946]"
container-test-run-dm-wireguard-star> peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [46081.702625] peer1 data-mesher[219]: time=2026-09-04T20:04:56.125Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer1 # [46081.702729] peer1 data-mesher[219]: time=2026-09-04T20:04:56.126Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer1 # [46081.702744] peer1 data-mesher[219]: time=2026-09-04T20:04:56.126Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer1 # [46081.702762] peer1 data-mesher[219]: time=2026-09-04T20:04:56.126Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer1 # [46081.705055] peer1 data-mesher[219]: time=2026-09-04T20:04:56.128Z level=INFO msg="peer connected" peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [46081.750673] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [46081.802311] peer1 systemd-logind[239]: New seat seat0.
container-test-run-dm-wireguard-star> peer1 # [46081.802456] peer1 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer1 # [46081.805291] peer1 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer1 # [46081.812275] peer1 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [46081.812322] peer1 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller # [46081.693434] controller data-mesher[230]: time=2026-09-04T20:04:56.116Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> controller # [46081.705269] controller data-mesher[230]: time=2026-09-04T20:04:56.128Z level=INFO msg="peer connected" peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> controller # [46081.745080] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [46081.779617] controller systemd-logind[250]: New seat seat0.
container-test-run-dm-wireguard-star> controller # [46081.779789] controller systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> controller # [46081.780695] controller systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> controller # [46081.810591] controller systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [46081.810708] controller systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller # [46082.944104] controller systemd-networkd[225]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer1 # [46083.201073] peer1 systemd-networkd[214]: 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: (finished: waiting for unit data-mesher.service, in 11.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/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-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/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-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> peer1 # [46091.694687] peer1 data-mesher[219]: time=2026-09-04T20:05:06.118Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46091.694687] peer1 data-mesher[219]: time=2026-09-04T20:05:06.118Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46091.702904] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer1 # [46091.702940] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46091.703166] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46091.703166] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46091.703204] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer1 # [46091.703236] peer1 data-mesher[219]: time=2026-09-04T20:05:06.126Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer1 # [46091.703304] peer1 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer1 # [46091.722210] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer1 # [46091.778215] peer1 data-mesher[219]: time=2026-09-04T20:05:06.201Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM status=204
container-test-run-dm-wireguard-star> peer1 # [46091.778270] peer1 dm-wg-star-publish[272]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer1 # [46091.779298] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [46091.780079] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [46091.780177] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer1 # [46091.780475] peer1 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer1 # [46091.845679] peer1 dm-wg-star-reconfig[288]: No controller data available yet, skipping
container-test-run-dm-wireguard-star> peer1 # [46091.846312] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [46091.846385] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [46091.846585] peer1 systemd[1]: Startup finished in 11.341s.
container-test-run-dm-wireguard-star> controller # [46091.694362] controller data-mesher[230]: time=2026-09-04T20:05:06.117Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> controller # [46091.694362] controller data-mesher[230]: time=2026-09-04T20:05:06.117Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> controller # [46091.694835] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46091.694835] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> controller # [46091.694835] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> controller # [46091.694895] controller data-mesher[230]: time=2026-09-04T20:05:06.118Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> controller # [46091.694964] controller systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> controller # [46091.696048] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...
container-test-run-dm-wireguard-star> controller # [46091.703092] controller data-mesher[230]: time=2026-09-04T20:05:06.126Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46091.703092] controller data-mesher[230]: time=2026-09-04T20:05:06.126Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46091.738299] controller data-mesher[230]: time=2026-09-04T20:05:06.161Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204
container-test-run-dm-wireguard-star> controller # [46091.738573] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [46091.738866] controller dm-wg-star-publish[282]: Status: 204 No Content
container-test-run-dm-wireguard-star> controller # [46091.740335] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.
container-test-run-dm-wireguard-star> controller # [46091.740521] controller systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> controller # [46091.783823] controller dm-wg-star-reconfig[289]: No peer data available yet, skipping
container-test-run-dm-wireguard-star> controller # [46091.784479] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [46091.802062] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> controller # [46091.802228] controller systemd[1]: Startup finished in 11.291s.
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.00 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.00 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9053:b839:432e:800a
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9053:b839:432e:800a, in 0.00 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f, 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.00 seconds)
container-test-run-dm-wireguard-star> peer2: systemd-nspawn running (pid 730)
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> peer1 # [46096.697568] peer1 data-mesher[219]: time=2026-09-04T20:05:11.120Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46096.697568] peer1 data-mesher[219]: time=2026-09-04T20:05:11.120Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46096.697905] peer1 data-mesher[219]: time=2026-09-04T20:05:11.120Z level=DEBUG msg="new file detected" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [46096.697905] peer1 data-mesher[219]: time=2026-09-04T20:05:11.121Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [46096.697905] peer1 data-mesher[219]: time=2026-09-04T20:05:11.121Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46096.698064] peer1 data-mesher[219]: time=2026-09-04T20:05:11.121Z level=INFO msg="received file request" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> peer1 # [46096.699181] peer1 data-mesher[219]: time=2026-09-04T20:05:11.122Z level=INFO msg="file transfer complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> peer1 # [46096.700389] peer1 data-mesher[219]: time=2026-09-04T20:05:11.123Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z written=true elapsed=2.68149ms
container-test-run-dm-wireguard-star> peer1 # [46096.701481] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [46096.703783] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [46096.703814] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46096.704253] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46096.704320] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46096.704337] peer1 data-mesher[219]: time=2026-09-04T20:05:11.127Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [46096.774543] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [46096.774598] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> controller # [46096.697129] controller data-mesher[230]: time=2026-09-04T20:05:11.120Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [46096.697129] controller data-mesher[230]: time=2026-09-04T20:05:11.120Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> controller # [46096.697728] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46096.697819] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=DEBUG msg="new file detected" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> controller # [46096.697840] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> controller # [46096.697840] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [46096.697883] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> controller # [46096.697901] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46096.697974] controller data-mesher[230]: time=2026-09-04T20:05:11.121Z level=INFO msg="received file request" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [46096.699161] controller data-mesher[230]: time=2026-09-04T20:05:11.122Z level=INFO msg="file transfer complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [46096.700391] controller data-mesher[230]: time=2026-09-04T20:05:11.123Z level=INFO msg="download complete" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi written=true elapsed=2.498346ms
container-test-run-dm-wireguard-star> controller # [46096.701466] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [46096.704060] controller data-mesher[230]: time=2026-09-04T20:05:11.127Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46096.704060] controller data-mesher[230]: time=2026-09-04T20:05:11.127Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46096.774237] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [46096.774399] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [46097.451909] peer2 systemd-journald[96]: Journal started
container-test-run-dm-wireguard-star> peer2 # [46097.451950] peer2 systemd-journald[96]: Runtime Journal (/run/log/journal/f361ddde36d34d57a9e44206b423dc2d) is 8M, max 3.7G, 3.7G free.
container-test-run-dm-wireguard-star> peer2 # [46097.452821] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> peer2 # [46097.456934] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer2 # [46097.457288] peer2 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> peer2 # [46097.457572] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> peer2 # [46097.463464] peer2 systemd-journald[96]: Time spent on flushing to /var/log/journal/f361ddde36d34d57a9e44206b423dc2d is 1.160ms for 6 entries.
container-test-run-dm-wireguard-star> peer2 # [46097.463464] peer2 systemd-journald[96]: System Journal (/var/log/journal/f361ddde36d34d57a9e44206b423dc2d) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer2 # [46097.464630] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer2 # [46097.464720] peer2 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [46097.464756] peer2 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [46097.465117] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer2 # [46097.465140] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [46097.465446] peer2 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer2 # [46097.465462] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer2 # [46097.475213] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer2 # [46097.475840] peer2 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer2 # [46097.501200] peer2 systemd-tmpfiles[145]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [46097.501342] peer2 systemd-tmpfiles[145]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [46097.501444] peer2 systemd-tmpfiles[145]: fchmod() of /var/log/journal/f361ddde36d34d57a9e44206b423dc2d failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [46097.501589] peer2 systemd-tmpfiles[145]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [46097.502661] peer2 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer2 # [46097.503334] peer2 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer2 # [46097.503665] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer2 # [46097.509597] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer2 # [46097.515732] peer2 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer2 # [46097.516246] peer2 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer2 # [46097.521030] peer2 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer2 # [46097.545740] peer2 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer2 # [46097.545827] peer2 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer2 # [46097.545972] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer2 # [46097.546390] peer2 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer2 # [46097.840234] peer2 systemd-networkd[213]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [46097.840366] peer2 systemd-networkd[213]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [46097.848363] peer2 systemd-networkd[213]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> peer2 # [46097.848544] peer2 systemd-networkd[213]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> peer2 # [46097.848719] peer2 systemd-networkd[213]: lo: Link UP
container-test-run-dm-wireguard-star> peer2 # [46097.848723] peer2 systemd-networkd[213]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [46097.848925] peer2 systemd-networkd[213]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer2 # [46097.849380] peer2 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer2 # [46097.850092] peer2 systemd-networkd[213]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer2 # [46097.850296] peer2 systemd-networkd[213]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer2 # [46097.850443] peer2 systemd-networkd[213]: eth1: Link UP
container-test-run-dm-wireguard-star> peer2 # [46097.850579] peer2 systemd-networkd[213]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [46097.850621] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer2 # [46097.871973] peer2 systemd-networkd[213]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer2 # [46097.871983] peer2 systemd-networkd[213]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [46097.881536] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer2 # [46097.975411] peer2 systemd-resolved[118]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer2 # [46097.975422] peer2 systemd-resolved[118]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer2 # [46097.975427] peer2 systemd-resolved[118]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer2 # [46097.975445] peer2 systemd-resolved[118]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
container-test-run-dm-wireguard-star> peer2 # [46097.987877] peer2 systemd-resolved[118]: Using system hostname 'peer2'.
container-test-run-dm-wireguard-star> peer2 # [46097.989231] peer2 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer2 # [46097.989313] peer2 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer2 # [46097.989365] peer2 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer2 # [46097.989433] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [46097.989457] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer2 # [46097.989483] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [46097.989497] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer2 # [46097.989512] peer2 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer2 # [46097.989533] peer2 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer2 # [46097.989631] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer2 # [46097.989747] peer2 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer2 # [46097.989861] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer2 # [46097.989879] peer2 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer2 # [46097.989909] peer2 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer2 # [46097.990940] peer2 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer2 # [46097.991490] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer2 # [46097.992058] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer2 # [46097.992933] peer2 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer2 # [46098.027723] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer2 # [46098.082074] peer2 nsncd[220]: Sep 04 20:05:12.505 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-dm-wireguard-star> peer2 # [46098.082130] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer2 # [46098.082176] peer2 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [46098.082217] peer2 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [46098.083054] peer2 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer2 # [46098.083493] peer2 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer2 # [46098.112069] peer2 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer2 # [46098.112659] peer2 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer2 # [46098.112688] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer2 # [46098.112700] peer2 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer2 # [46098.139616] peer2 dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer2 # [46098.140108] peer2 dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer2 # [46098.140108] peer2 dbus-broker-launch[221]: Invalid user-name in /nix/store/2vdmzcm94vw773ig833bsswy8c0krm4j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer2 # [46098.140417] peer2 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer2 # [46098.144139] peer2 dbus-broker-launch[221]: Ready
container-test-run-dm-wireguard-star> peer2 # [46098.328898] peer2 data-mesher[218]: time=2026-09-04T20:05:12.752Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-dm-wireguard-star> peer2 # [46098.330067] peer2 data-mesher[218]: time=2026-09-04T20:05:12.753Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z: [/dns/controller.clan/tcp/7946]} {12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi: [/dns/peer1.clan/tcp/7946]} {12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer2 # [46098.330099] peer2 data-mesher[218]: time=2026-09-04T20:05:12.753Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer2 # [46098.361254] peer2 data-mesher[218]: time=2026-09-04T20:05:12.784Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer2 # [46098.361326] peer2 data-mesher[218]: time=2026-09-04T20:05:12.784Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer2 # [46098.363683] peer2 data-mesher[218]: time=2026-09-04T20:05:12.786Z level=INFO msg="libp2p host created" peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.3/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::3/tcp/7946 /ip6/fda1:5c8::2e78:e6d2:d649:e9b7/tcp/7946]"
container-test-run-dm-wireguard-star> peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [46098.363711] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer2 # [46098.363761] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer2 # [46098.363793] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer2 # [46098.363807] peer2 data-mesher[218]: time=2026-09-04T20:05:12.787Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> controller # [46098.366219] controller data-mesher[230]: time=2026-09-04T20:05:12.789Z level=INFO msg="peer connected" peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [46098.368030] peer1 data-mesher[219]: time=2026-09-04T20:05:12.791Z level=INFO msg="peer connected" peer_id=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [46098.366016] peer2 data-mesher[218]: time=2026-09-04T20:05:12.789Z level=INFO msg="peer connected" peer_id=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [46098.367788] peer2 data-mesher[218]: time=2026-09-04T20:05:12.791Z level=INFO msg="peer connected" peer_id=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [46098.436059] peer2 systemd-logind[238]: New seat seat0.
container-test-run-dm-wireguard-star> peer2 # [46098.436246] peer2 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer2 # [46098.437576] peer2 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer2 # [46098.466420] peer2 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [46098.466568] peer2 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> peer2 # [46099.584077] peer2 systemd-networkd[213]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer2 # [46101.698623] peer2 data-mesher[218]: time=2026-09-04T20:05:16.121Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46101.698623] peer2 data-mesher[218]: time=2026-09-04T20:05:16.121Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=DEBUG msg="new file detected" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=DEBUG msg="new file detected" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46101.699167] peer2 data-mesher[218]: time=2026-09-04T20:05:16.122Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46101.702558] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [46101.759151] peer2 dm-wg-star-reconfig[268]: No controller data available yet, skipping
container-test-run-dm-wireguard-star> peer2 # [46101.759729] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [46101.759851] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [46101.864886] peer2 data-mesher[218]: time=2026-09-04T20:05:16.288Z level=INFO msg="download complete" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM signed_at="2026-09-04 20:05:06.199 +0000 UTC" signed_by="3/xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z written=true elapsed=166.094202ms
container-test-run-dm-wireguard-star> peer2 # [46101.866097] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [46101.933235] peer2 data-mesher[218]: time=2026-09-04T20:05:16.356Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-04 20:05:06.159 +0000 UTC" signed_by="rvPAGFxv0XOKJuP3eVbs0qQahkgv9hKzp+jh6pweG+Q=" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z written=true elapsed=234.466885ms
container-test-run-dm-wireguard-star> peer1 # [46101.705268] peer1 data-mesher[219]: time=2026-09-04T20:05:16.128Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [46101.705268] peer1 data-mesher[219]: time=2026-09-04T20:05:16.128Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46101.705764] peer1 data-mesher[219]: time=2026-09-04T20:05:16.129Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46101.705837] peer1 data-mesher[219]: time=2026-09-04T20:05:16.129Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46101.705859] peer1 data-mesher[219]: time=2026-09-04T20:05:16.129Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [46101.698105] controller data-mesher[230]: time=2026-09-04T20:05:16.121Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [46101.698105] controller data-mesher[230]: time=2026-09-04T20:05:16.121Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46101.698840] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> controller # [46101.698840] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46101.698950] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [46101.699122] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="received file request" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> controller # [46101.699218] controller data-mesher[230]: time=2026-09-04T20:05:16.122Z level=INFO msg="received file request" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [46101.699935] controller data-mesher[230]: time=2026-09-04T20:05:16.123Z level=INFO msg="file transfer complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM
container-test-run-dm-wireguard-star> controller # [46101.699965] controller data-mesher[230]: time=2026-09-04T20:05:16.123Z level=INFO msg="file transfer complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [46101.705581] controller data-mesher[230]: time=2026-09-04T20:05:16.128Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46101.705581] controller data-mesher[230]: time=2026-09-04T20:05:16.128Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer2 # [46101.949231] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [46101.949335] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [46106.705997] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [46106.705997] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46106.706486] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46106.706571] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46106.706600] peer1 data-mesher[219]: time=2026-09-04T20:05:21.129Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [46106.699508] peer2 data-mesher[218]: time=2026-09-04T20:05:21.122Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46106.699508] peer2 data-mesher[218]: time=2026-09-04T20:05:21.122Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state...
container-test-run-dm-wireguard-star> controller # [46106.699048] controller data-mesher[230]: time=2026-09-04T20:05:21.122Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [46106.699048] controller data-mesher[230]: time=2026-09-04T20:05:21.122Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46106.699770] controller data-mesher[230]: time=2026-09-04T20:05:21.123Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> controller # [46106.699865] controller data-mesher[230]: time=2026-09-04T20:05:21.123Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46106.699883] controller data-mesher[230]: time=2026-09-04T20:05:21.123Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [46106.706268] controller data-mesher[230]: time=2026-09-04T20:05:21.129Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46106.706268] controller data-mesher[230]: time=2026-09-04T20:05:21.129Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer1 # [46108.364976] peer1 data-mesher[219]: time=2026-09-04T20:05:22.788Z level=INFO msg="received state sync from peer" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer1 # [46108.364976] peer1 data-mesher[219]: time=2026-09-04T20:05:22.788Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer2 # [46108.364792] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer2 # [46108.364792] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> peer2 # [46108.365230] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer2 # [46108.365285] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> peer2 # [46108.365285] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer2 # [46108.365362] peer2 data-mesher[218]: time=2026-09-04T20:05:22.788Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer2 # [46108.365446] peer2 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer2 # [46108.366219] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer2 # [46108.446037] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [46108.450467] peer2 data-mesher[218]: time=2026-09-04T20:05:22.873Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU status=204
container-test-run-dm-wireguard-star> peer2 # [46108.450572] peer2 dm-wg-star-publish[286]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer2 # [46108.452372] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [46108.474076] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer2 # [46108.474324] peer2 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer2 # [46108.523072] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [46108.523203] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [46111.707250] peer1 data-mesher[219]: time=2026-09-04T20:05:26.130Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [46111.707582] peer1 data-mesher[219]: time=2026-09-04T20:05:26.130Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46111.707633] peer1 data-mesher[219]: time=2026-09-04T20:05:26.130Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer1 # [46111.707734] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=DEBUG msg="new file detected" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> peer1 # [46111.707754] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46111.707754] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [46111.707790] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> peer1 # [46111.707803] peer1 data-mesher[219]: time=2026-09-04T20:05:26.131Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer1 # [46111.724259] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [46111.780600] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [46111.780708] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [46111.815940] peer1 data-mesher[219]: time=2026-09-04T20:05:26.239Z level=INFO msg="download complete" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr written=true elapsed=108.152955ms
container-test-run-dm-wireguard-star> peer2 # [46111.701165] peer2 data-mesher[218]: time=2026-09-04T20:05:26.124Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46111.701165] peer2 data-mesher[218]: time=2026-09-04T20:05:26.124Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46111.701694] peer2 data-mesher[218]: time=2026-09-04T20:05:26.125Z level=INFO msg="received file request" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> peer2 # [46111.702235] peer2 data-mesher[218]: time=2026-09-04T20:05:26.125Z level=INFO msg="file transfer complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> peer2 # [46111.707473] peer2 data-mesher[218]: time=2026-09-04T20:05:26.130Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer2 # [46111.707473] peer2 data-mesher[218]: time=2026-09-04T20:05:26.130Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer2 # [46111.707901] peer2 data-mesher[218]: time=2026-09-04T20:05:26.131Z level=INFO msg="received file request" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> peer2 # [46111.708167] peer2 data-mesher[218]: time=2026-09-04T20:05:26.131Z level=INFO msg="file transfer complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi network="aHBok+h1Mutza+pvqetO9O3SR3wIc4S11P/IRa1G3XA=" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> controller # [46111.700820] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [46111.700820] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46111.701374] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> controller # [46111.701491] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="new file detected" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr name=dm_wg_star_wg_star/3_xQ2NtqQD68KY7shEpHX5EXIzotWqFA2ZN1uUVimaM name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> controller # [46111.701507] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46111.701507] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [46111.701547] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU
container-test-run-dm-wireguard-star> controller # [46111.701567] controller data-mesher[230]: time=2026-09-04T20:05:26.124Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> controller # [46111.704453] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [46111.805965] controller data-mesher[230]: time=2026-09-04T20:05:26.229Z level=INFO msg="download complete" name=dm_wg_star_wg_star/jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU signed_at="2026-09-04 20:05:22.867 +0000 UTC" signed_by="jzaRWKhemTMkDWl0QUaQQqEQSkHbkHbLj6XSbyFRmHU=" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr written=true elapsed=104.403855ms
container-test-run-dm-wireguard-star> controller # [46111.832295] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [46111.832340] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> controller # [46113.366121] controller data-mesher[230]: time=2026-09-04T20:05:27.789Z level=INFO msg="received state sync from peer" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> controller # [46113.366121] controller data-mesher[230]: time=2026-09-04T20:05:27.789Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer2 # [46113.365644] peer2 data-mesher[218]: time=2026-09-04T20:05:27.788Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-dm-wireguard-star> peer2 # [46113.365644] peer2 data-mesher[218]: time=2026-09-04T20:05:27.788Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer2 # [46113.366457] peer2 data-mesher[218]: time=2026-09-04T20:05:27.789Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46113.366617] peer2 data-mesher[218]: time=2026-09-04T20:05:27.789Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer2 # [46113.366644] peer2 data-mesher[218]: time=2026-09-04T20:05:27.789Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [46116.708689] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [46116.708938] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=DEBUG msg="initiating state exchange" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46116.709085] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer1 # [46116.709240] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=INFO msg="state exchange complete" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z timeout=5s
container-test-run-dm-wireguard-star> peer1 # [46116.709258] peer1 data-mesher[219]: time=2026-09-04T20:05:31.132Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [46116.702573] peer2 data-mesher[218]: time=2026-09-04T20:05:31.125Z level=INFO msg="received state sync from peer" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2 # [46116.702573] peer2 data-mesher[218]: time=2026-09-04T20:05:31.125Z level=INFO msg="merging remote state" peer=12D3KooWN7H87EXA2qPGMcpR83pAStJ7fM3rCTLFky3oWaw1wf1Z
container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state...
container-test-run-dm-wireguard-star> controller # [46116.702258] controller data-mesher[230]: time=2026-09-04T20:05:31.125Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [46116.702258] controller data-mesher[230]: time=2026-09-04T20:05:31.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46116.702823] controller data-mesher[230]: time=2026-09-04T20:05:31.126Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> controller # [46116.702929] controller data-mesher[230]: time=2026-09-04T20:05:31.126Z level=INFO msg="state exchange complete" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr timeout=5s
container-test-run-dm-wireguard-star> controller # [46116.702951] controller data-mesher[230]: time=2026-09-04T20:05:31.126Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [46116.708888] controller data-mesher[230]: time=2026-09-04T20:05:31.132Z level=INFO msg="received state sync from peer" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> controller # [46116.708908] controller data-mesher[230]: time=2026-09-04T20:05:31.132Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer1 # [46118.367134] peer1 data-mesher[219]: time=2026-09-04T20:05:32.790Z level=INFO msg="received state sync from peer" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer1 # [46118.367134] peer1 data-mesher[219]: time=2026-09-04T20:05:32.790Z level=INFO msg="merging remote state" peer=12D3KooWKTQjGbzhoYsEnTa9HNoyVYhYRJAy45q7qd26Ys2PuWhr
container-test-run-dm-wireguard-star> peer2 # [46118.366918] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-dm-wireguard-star> peer2 # [46118.366918] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> peer2 # [46118.367368] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=INFO msg="merging remote state" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi
container-test-run-dm-wireguard-star> peer2 # [46118.367462] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=INFO msg="state exchange complete" peer=12D3KooWQtiEa6KrH69xkBDEBfN3PKJNk2RazVQspakDnneRiTAi timeout=5s
container-test-run-dm-wireguard-star> peer2 # [46118.367483] peer2 data-mesher[218]: time=2026-09-04T20:05:32.790Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2: (finished: waiting for unit data-mesher.service, in 23.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/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-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/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-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 $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1, in 0.01 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::9053:b839:432e:800a
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::9053:b839:432e:800a, in 0.00 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7, in 0.00 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::2e78:e6d2:d649:e9b7, in 0.00 seconds)
container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::11ba:c4d4:8af6:629f, in 0.00 seconds)
container-test-run-dm-wireguard-star> (finished: run the VM test script, in 39.88 seconds)
container-test-run-dm-wireguard-star> test script finished in 39.90s
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> kill NspawnMachine (pid 730)
container-test-run-dm-wireguard-star> Container controller terminated by signal KILL.
container-test-run-dm-wireguard-star> peer2 # [46119.980077] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [46119.980724] peer2 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer2 # [46119.984044] peer2 systemd[1]: Startup finished in 22.765s.
container-test-run-dm-wireguard-star> peer2 # [46120.225375] peer2 systemd-networkd[213]: eth1: Link DOWN
container-test-run-dm-wireguard-star> peer2 # [46120.225383] peer2 systemd-networkd[213]: eth1: Lost carrier
container-test-run-dm-wireguard-star> peer2 # [46120.246210] peer2 systemd-networkd[213]: eth1: Lost IPv6LL address fe80::fc32:b7ff:fea6:2f65.
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.21 seconds)

post-build step Upload to niks3: ok
time=2026-09-04T20:05:34.972Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-09-04T20:05:35.816Z level=INFO msg="Uploading 1 narinfos"
time=2026-09-04T20:05:35.917Z level=INFO msg="Upload complete. (996ms)"