don't know how to build these paths:
  /nix/store/r3qkd78k4pmq3q8v6f9h3rs9lah0fc48-container-test-run-dm-wireguard-star.drv
warning: ignoring the client-specified setting 'store', because it is a restricted setting and you are not a trusted user
building '/nix/store/2i4c76h6gj5rvjcps1mx5ryvy2kfm73i-system-path.drv' on nix-store.geninf.io:50051
system-path> build01: building 2i4c76h6gj5rvjcps1mx5ryvy2kfm73i-system-path.drv
system-path> structuredAttrs is enabled
system-path> created 1724 symlinks in user environment
building '/nix/store/rpjxjcxiicjl9v6srgm29r0j6h3zf7pk-dbus-1.drv' on nix-store.geninf.io:50051
dbus-1> build01: building rpjxjcxiicjl9v6srgm29r0j6h3zf7pk-dbus-1.drv
building '/nix/store/rbxxf0mcmgja8i5bp9z4n2jwn5i24yic-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051
X-Restart-Triggers-dbus-broker> build01: building rbxxf0mcmgja8i5bp9z4n2jwn5i24yic-X-Restart-Triggers-dbus-broker.drv
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
building '/nix/store/fxa8cb55zcfm01sisjm4356rxix7dk8z-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051
unit-dbus-broker.service> build01: building fxa8cb55zcfm01sisjm4356rxix7dk8z-unit-dbus-broker.service.drv
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/791wal576bn5sv7nfxikw9hcy8ar4cv1-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051
unit-dbus-broker.service> build01: building 791wal576bn5sv7nfxikw9hcy8ar4cv1-unit-dbus-broker.service.drv
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/489aypcfr29xwn943fnq5i7j8ki371kj-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building 489aypcfr29xwn943fnq5i7j8ki371kj-system-units.drv
building '/nix/store/aj5n3l5rgg6vp1ywlk9b1wf83zvmc6h6-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building aj5n3l5rgg6vp1ywlk9b1wf83zvmc6h6-system-units.drv
building '/nix/store/93dzrvwvm2lw50l9pxvjb1y4ks0782yi-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building 93dzrvwvm2lw50l9pxvjb1y4ks0782yi-system-units.drv
building '/nix/store/wimmv6ffh3yjgm96zvih6ja024374r0g-user-units.drv' on nix-store.geninf.io:50051
user-units> build01: building wimmv6ffh3yjgm96zvih6ja024374r0g-user-units.drv
building '/nix/store/mjy9yacj92mc1icajz1nf8nyvz0lp12k-etc.drv' on nix-store.geninf.io:50051
etc> build01: building mjy9yacj92mc1icajz1nf8nyvz0lp12k-etc.drv
building '/nix/store/9ic38fmpj4avfif7qaxjb3w809zkq816-etc.drv' on nix-store.geninf.io:50051
etc> build01: building 9ic38fmpj4avfif7qaxjb3w809zkq816-etc.drv
building '/nix/store/f3nvv7kb2908wk28cdmrprb152yrw969-etc.drv' on nix-store.geninf.io:50051
etc> build01: building f3nvv7kb2908wk28cdmrprb152yrw969-etc.drv
building '/nix/store/29z7zagwx4bikhninp4cbv34yyr2m91k-activate.drv' on nix-store.geninf.io:50051
activate> build01: building 29z7zagwx4bikhninp4cbv34yyr2m91k-activate.drv
activate> structuredAttrs is enabled
building '/nix/store/j0lcdv4rvb0x2bh07r57qg7iybnkfddi-activate.drv' on nix-store.geninf.io:50051
activate> build01: building j0lcdv4rvb0x2bh07r57qg7iybnkfddi-activate.drv
activate> structuredAttrs is enabled
building '/nix/store/vd9k7wkdr2qjdpi8v2vfxwmmnwaas2qm-activate.drv' on nix-store.geninf.io:50051
activate> build01: building vd9k7wkdr2qjdpi8v2vfxwmmnwaas2qm-activate.drv
activate> structuredAttrs is enabled
building '/nix/store/3gvrnd2fjdn89vhgfh1dwg7wphbx9rkl-nixos-system-peer2-test.drv' on nix-store.geninf.io:50051
nixos-system-peer2-test> build01: building 3gvrnd2fjdn89vhgfh1dwg7wphbx9rkl-nixos-system-peer2-test.drv
nixos-system-peer2-test> structuredAttrs is enabled
building '/nix/store/64hzlnj9xsxblym3ll1qmjiy6rcwhmpw-nixos-system-controller-test.drv' on nix-store.geninf.io:50051
nixos-system-controller-test> build01: building 64hzlnj9xsxblym3ll1qmjiy6rcwhmpw-nixos-system-controller-test.drv
nixos-system-controller-test> structuredAttrs is enabled
building '/nix/store/1s4cqpdfd7fidx823j6qncx0zsgmh2bl-nixos-system-peer1-test.drv' on nix-store.geninf.io:50051
nixos-system-peer1-test> build01: building 1s4cqpdfd7fidx823j6qncx0zsgmh2bl-nixos-system-peer1-test.drv
nixos-system-peer1-test> structuredAttrs is enabled
building '/nix/store/jmc5pl459cdmgwjpgjn7rwfp0kpdlxhl-run-peer2-nspawn.drv' on nix-store.geninf.io:50051
run-peer2-nspawn> build01: building jmc5pl459cdmgwjpgjn7rwfp0kpdlxhl-run-peer2-nspawn.drv
building '/nix/store/w73yrypayl2lx68nqiv794f8pr0c0jb9-run-controller-nspawn.drv' on nix-store.geninf.io:50051
run-controller-nspawn> build01: building w73yrypayl2lx68nqiv794f8pr0c0jb9-run-controller-nspawn.drv
building '/nix/store/d0pb7gjhypjvvk4mmknapqhdzyyy1xsb-run-peer1-nspawn.drv' on nix-store.geninf.io:50051
run-peer1-nspawn> build01: building d0pb7gjhypjvvk4mmknapqhdzyyy1xsb-run-peer1-nspawn.drv
building '/nix/store/w8dsmjag7mwmqhr2kfc7v4ahd0jxrkkp-driverConfiguration.json.drv' on nix-store.geninf.io:50051
driverConfiguration.json> build01: building w8dsmjag7mwmqhr2kfc7v4ahd0jxrkkp-driverConfiguration.json.drv
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/rv6z2wm9jb8xnvhsnbfwf6d0hzrdka05-nixos-test-driver-dm-wireguard-star.drv' on nix-store.geninf.io:50051
nixos-test-driver-dm-wireguard-star> build01: building rv6z2wm9jb8xnvhsnbfwf6d0hzrdka05-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/r3qkd78k4pmq3q8v6f9h3rs9lah0fc48-container-test-run-dm-wireguard-star.drv' on nix-store.geninf.io:50051
container-test-run-dm-wireguard-star> build01: building r3qkd78k4pmq3q8v6f9h3rs9lah0fc48-container-test-run-dm-wireguard-star.drv
container-test-run-dm-wireguard-star> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-dm-wireguard-star> start all VLans
container-test-run-dm-wireguard-star> (finished: start all VLans, in 0.00 seconds)
container-test-run-dm-wireguard-star> 
container-test-run-dm-wireguard-star> Test will time out and terminate in 3600.0 seconds
container-test-run-dm-wireguard-star> run the VM test script
container-test-run-dm-wireguard-star> additionally exposed symbols:
container-test-run-dm-wireguard-star>     controller, peer1, peer2,
container-test-run-dm-wireguard-star>     vlan1,
container-test-run-dm-wireguard-star>     start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
container-test-run-dm-wireguard-star> controller: systemd-nspawn running (pid 50)
container-test-run-dm-wireguard-star> controller: Waiting for journal at /build/vm-state-controller/var/log/journal...
container-test-run-dm-wireguard-star> peer1: systemd-nspawn running (pid 53)
container-test-run-dm-wireguard-star> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...
container-test-run-dm-wireguard-star> controller: waiting for unit data-mesher.service
container-test-run-dm-wireguard-star> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-dm-wireguard-star> nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-nspawn(controller): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-dm-wireguard-star> ░ Spawning container peer1 on /build/vm-state-peer1.
container-test-run-dm-wireguard-star> ░ Spawning container controller on /build/vm-state-controller.
container-test-run-dm-wireguard-star> controller # No journal files were found.
container-test-run-dm-wireguard-star> controller # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> peer1 # No journal files were found.
container-test-run-dm-wireguard-star> peer1 # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> controller # [2638073.883963] controller systemd-journald[108]: Journal started
container-test-run-dm-wireguard-star> controller # [2638073.884063] controller systemd-journald[108]: Runtime Journal (/run/log/journal/aa3c870178d44a7dafe9765f601fd371) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> controller # [2638073.905359] controller systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> controller # [2638073.920977] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [2638073.969778] controller systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> controller # [2638073.970881] controller systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [2638073.971692] controller systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [2638074.022720] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/aa3c870178d44a7dafe9765f601fd371 is 1.628ms for 7 entries.
container-test-run-dm-wireguard-star> controller # [2638074.022720] controller systemd-journald[108]: System Journal (/var/log/journal/aa3c870178d44a7dafe9765f601fd371) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> controller # [2638074.070681] controller systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> controller # [2638074.070919] controller systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> controller # [2638074.072557] controller systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> controller # [2638074.074261] controller systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> controller # [2638074.074370] controller systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> controller # [2638074.074426] controller systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> controller # [2638074.075301] controller systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> controller # [2638074.075509] controller systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> controller # [2638074.075559] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [2638074.076644] controller systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> controller # [2638074.077850] controller systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> controller # [2638074.077889] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> controller # [2638074.078983] controller systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> controller # [2638074.094212] controller systemd-tmpfiles[219]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [2638074.094403] controller systemd-tmpfiles[219]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [2638074.094543] controller systemd-tmpfiles[219]: fchmod() of /var/log/journal/aa3c870178d44a7dafe9765f601fd371 failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [2638074.094741] controller systemd-tmpfiles[219]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [2638074.097752] controller systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> controller # [2638074.098857] controller systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> controller # [2638074.099618] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> controller # [2638074.112692] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> controller # [2638074.126068] controller systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> controller # [2638074.127428] controller systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer1 # [2638073.877311] peer1 systemd-journald[99]: Journal started
container-test-run-dm-wireguard-star> controller # [2638074.139071] controller systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [2638073.877370] peer1 systemd-journald[99]: Runtime Journal (/run/log/journal/1b7318501c694b1f8e51cad5ecba048d) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> controller # [2638074.185718] controller systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer1 # [2638073.903559] peer1 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> peer1 # [2638073.919771] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> peer1 # [2638073.969526] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer1 # [2638073.970583] peer1 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> peer1 # [2638073.971385] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> peer1 # [2638074.022788] peer1 systemd-journald[99]: Time spent on flushing to /var/log/journal/1b7318501c694b1f8e51cad5ecba048d is 1.861ms for 7 entries.
container-test-run-dm-wireguard-star> peer1 # [2638074.022788] peer1 systemd-journald[99]: System Journal (/var/log/journal/1b7318501c694b1f8e51cad5ecba048d) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer1 # [2638074.070667] peer1 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer1 # [2638074.070913] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer1 # [2638074.072504] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer1 # [2638074.074246] peer1 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [2638074.074357] peer1 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [2638074.074414] peer1 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer1 # [2638074.075300] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer1 # [2638074.075498] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer1 # [2638074.075542] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [2638074.076780] peer1 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer1 # [2638074.077712] peer1 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer1 # [2638074.077755] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer1 # [2638074.078929] peer1 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer1 # [2638074.092762] peer1 systemd-tmpfiles[208]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [2638074.092956] peer1 systemd-tmpfiles[208]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [2638074.093088] peer1 systemd-tmpfiles[208]: fchmod() of /var/log/journal/1b7318501c694b1f8e51cad5ecba048d failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [2638074.093294] peer1 systemd-tmpfiles[208]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [2638074.097624] peer1 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer1 # [2638074.098864] peer1 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer1 # [2638074.099665] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer1 # [2638074.113411] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer1 # [2638074.119625] peer1 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer1 # [2638074.120929] peer1 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer1 # [2638074.132253] peer1 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [2638074.183894] peer1 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer1 # [2638074.870411] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [2638074.908166] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [2638075.590569] controller systemd-networkd[220]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [2638075.590664] controller systemd-networkd[220]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [2638075.606185] controller systemd-networkd[220]: /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 # [2638075.606355] controller systemd-networkd[220]: /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 # [2638075.606534] controller systemd-networkd[220]: lo: Link UP
container-test-run-dm-wireguard-star> controller # [2638075.606540] controller systemd-networkd[220]: lo: Gained carrier
container-test-run-dm-wireguard-star> controller # [2638075.606737] controller systemd-networkd[220]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> controller # [2638075.607153] controller systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> controller # [2638075.608072] controller systemd-networkd[220]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> controller # [2638075.608397] controller systemd-networkd[220]: wg-star: netdev ready
container-test-run-dm-wireguard-star> controller # [2638075.608521] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> controller # [2638075.608778] controller systemd-networkd[220]: eth1: Link UP
container-test-run-dm-wireguard-star> controller # [2638075.608991] controller systemd-networkd[220]: eth1: Gained carrier
container-test-run-dm-wireguard-star> controller # [2638075.648981] controller systemd-networkd[220]: wg-star: Link UP
container-test-run-dm-wireguard-star> controller # [2638075.648993] controller systemd-networkd[220]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> controller # [2638075.650088] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer1 # [2638075.736644] peer1 systemd-networkd[209]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [2638075.736749] peer1 systemd-networkd[209]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [2638075.749308] peer1 systemd-networkd[209]: /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 # [2638075.749490] peer1 systemd-networkd[209]: /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 # [2638075.749651] peer1 systemd-networkd[209]: lo: Link UP
container-test-run-dm-wireguard-star> peer1 # [2638075.749663] peer1 systemd-networkd[209]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [2638075.749850] peer1 systemd-networkd[209]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer1 # [2638075.750515] peer1 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer1 # [2638075.751903] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer1 # [2638075.751974] peer1 systemd-networkd[209]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer1 # [2638075.752366] peer1 systemd-networkd[209]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer1 # [2638075.752751] peer1 systemd-networkd[209]: eth1: Link UP
container-test-run-dm-wireguard-star> peer1 # [2638075.752958] peer1 systemd-networkd[209]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [2638075.797659] peer1 systemd-networkd[209]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer1 # [2638075.797664] peer1 systemd-networkd[209]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [2638075.798835] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer1 # [2638076.623992] peer1 systemd-resolved[166]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer1 # [2638076.624019] peer1 systemd-resolved[166]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer1 # [2638076.624024] peer1 systemd-resolved[166]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer1 # [2638076.624061] peer1 systemd-resolved[166]: 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 # [2638076.646160] peer1 systemd-resolved[166]: Using system hostname 'peer1'.
container-test-run-dm-wireguard-star> peer1 # [2638076.648054] peer1 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer1 # [2638076.648153] peer1 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer1 # [2638076.648222] peer1 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer1 # [2638076.648318] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [2638076.648358] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer1 # [2638076.648387] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [2638076.648402] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer1 # [2638076.648422] peer1 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer1 # [2638076.648455] peer1 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer1 # [2638076.648582] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer1 # [2638076.648708] peer1 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer1 # [2638076.648819] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer1 # [2638076.648841] peer1 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer1 # [2638076.648874] peer1 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer1 # [2638076.650234] peer1 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer1 # [2638076.651012] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer1 # [2638076.651874] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer1 # [2638076.653115] peer1 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer1 # [2638076.699865] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> controller # [2638076.677201] controller systemd-resolved[170]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> controller # [2638076.677213] controller systemd-resolved[170]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> controller # [2638076.677217] controller systemd-resolved[170]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> controller # [2638076.677251] controller systemd-resolved[170]: 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 # [2638076.698086] controller systemd-resolved[170]: Using system hostname 'controller'.
container-test-run-dm-wireguard-star> controller # [2638076.699767] controller systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> controller # [2638076.699852] controller systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> controller # [2638076.699922] controller systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> controller # [2638076.700013] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.
container-test-run-dm-wireguard-star> controller # [2638076.700057] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [2638076.700085] controller systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> controller # [2638076.700105] controller systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> controller # [2638076.700143] controller systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> controller # [2638076.700262] controller systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> controller # [2638076.700373] controller systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> controller # [2638076.700538] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> controller # [2638076.700567] controller systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> controller # [2638076.700601] controller systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> controller # [2638076.701771] controller systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> controller # [2638076.702511] controller systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> controller # [2638076.703262] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> controller # [2638076.704486] controller systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> controller # [2638076.721011] controller systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> controller # [2638076.953054] controller nsncd[235]: Oct 04 20:14:56.938 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 # [2638076.952994] controller systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> controller # [2638076.953071] controller systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> controller # [2638076.953136] controller systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> controller # [2638076.954590] controller systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> controller # [2638076.955425] controller systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> controller # [2638077.003482] controller systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> controller # [2638077.004651] controller systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> controller # [2638077.004690] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> controller # [2638077.004717] controller systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> controller # [2638077.143848] controller dbus-broker-launch[236]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> controller # [2638077.144889] controller dbus-broker-launch[236]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> controller # [2638077.144889] controller dbus-broker-launch[236]: Invalid user-name in /nix/store/l02zj79nnnv7h7qn3a3l5353m9faqb1g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> controller # [2638077.145331] controller systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> controller # [2638077.152318] controller dbus-broker-launch[236]: Ready
container-test-run-dm-wireguard-star> peer1 # [2638076.978611] peer1 nsncd[224]: Oct 04 20:14:56.964 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 # [2638076.978645] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer1 # [2638076.978730] peer1 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [2638076.978792] peer1 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [2638076.996392] peer1 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer1 # [2638076.997279] peer1 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer1 # [2638077.007445] peer1 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer1 # [2638077.008467] peer1 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer1 # [2638077.008507] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer1 # [2638077.008531] peer1 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer1 # [2638077.120119] peer1 systemd-networkd[209]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer1 # [2638077.132018] peer1 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer1 # [2638077.133174] peer1 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer1 # [2638077.133174] peer1 dbus-broker-launch[225]: Invalid user-name in /nix/store/l02zj79nnnv7h7qn3a3l5353m9faqb1g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer1 # [2638077.134070] peer1 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer1 # [2638077.142329] peer1 dbus-broker-launch[225]: Ready
container-test-run-dm-wireguard-star> controller # [2638077.444140] controller systemd-networkd[220]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> controller # [2638077.994484] controller data-mesher[233]: time=2026-10-04T20:14:57.980Z 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 # [2638077.995578] controller data-mesher[233]: time=2026-10-04T20:14:57.981Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ: [/dns/controller.clan/tcp/7946]} {12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf: [/dns/peer1.clan/tcp/7946]} {12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> controller # [2638077.995649] controller data-mesher[233]: time=2026-10-04T20:14:57.981Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> controller # [2638078.000947] controller data-mesher[233]: time=2026-10-04T20:14:57.986Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> controller # [2638078.001053] controller data-mesher[233]: time=2026-10-04T20:14:57.986Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> controller # [2638078.005135] controller data-mesher[233]: time=2026-10-04T20:14:57.990Z level=INFO msg="libp2p host created" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ 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::28bc:6cf0:1e15:8c64/tcp/7946]"
container-test-run-dm-wireguard-star> controller # [2638078.005189] controller data-mesher[233]: time=2026-10-04T20:14:57.991Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> controller # [2638078.005189] controller data-mesher[233]: time=2026-10-04T20:14:57.991Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> controller # [2638078.005189] controller data-mesher[233]: time=2026-10-04T20:14:57.991Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> controller # [2638078.005189] controller data-mesher[233]: time=2026-10-04T20:14:57.991Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> controller # [2638078.005313] controller data-mesher[233]: time=2026-10-04T20:14:57.991Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> controller # [2638078.005396] controller data-mesher[233]: time=2026-10-04T20:14:57.991Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> controller # [2638078.005426] controller data-mesher[233]: time=2026-10-04T20:14:57.991Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> controller # [2638078.010043] controller data-mesher[233]: time=2026-10-04T20:14:57.995Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [2638077.987948] peer1 data-mesher[222]: time=2026-10-04T20:14:57.973Z 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 # [2638077.989133] peer1 data-mesher[222]: time=2026-10-04T20:14:57.974Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ: [/dns/controller.clan/tcp/7946]} {12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf: [/dns/peer1.clan/tcp/7946]} {12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer1 # [2638077.989186] peer1 data-mesher[222]: time=2026-10-04T20:14:57.974Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer1 # [2638078.000836] peer1 data-mesher[222]: time=2026-10-04T20:14:57.986Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer1 # [2638078.000962] peer1 data-mesher[222]: time=2026-10-04T20:14:57.986Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer1 # [2638078.006011] peer1 data-mesher[222]: time=2026-10-04T20:14:57.990Z level=INFO msg="libp2p host created" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf 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::23fd:a719:7f48:87f1/tcp/7946]"
container-test-run-dm-wireguard-star> peer1 # [2638078.006115] peer1 data-mesher[222]: time=2026-10-04T20:14:57.991Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer1 # [2638078.006146] peer1 data-mesher[222]: time=2026-10-04T20:14:57.991Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [2638078.006146] peer1 data-mesher[222]: time=2026-10-04T20:14:57.991Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [2638078.006146] peer1 data-mesher[222]: time=2026-10-04T20:14:57.991Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer1 # [2638078.006859] peer1 data-mesher[222]: time=2026-10-04T20:14:57.992Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer1 # [2638078.006859] peer1 data-mesher[222]: time=2026-10-04T20:14:57.992Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer1 # [2638078.006982] peer1 data-mesher[222]: time=2026-10-04T20:14:57.992Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer1 # [2638078.010648] peer1 data-mesher[222]: time=2026-10-04T20:14:57.996Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [2638078.288318] peer1 systemd-logind[242]: New seat seat0.
container-test-run-dm-wireguard-star> peer1 # [2638078.288550] peer1 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer1 # [2638078.289677] peer1 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer1 # [2638078.340224] peer1 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [2638078.340435] peer1 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller # [2638078.284038] controller systemd-logind[253]: New seat seat0.
container-test-run-dm-wireguard-star> controller # [2638078.284277] controller systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> controller # [2638078.285820] controller systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> controller # [2638078.340428] controller systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [2638078.340498] controller systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller: still waiting for container 'controller' to reach ready state...
container-test-run-dm-wireguard-star> controller # [2638088.006444] controller data-mesher[233]: time=2026-10-04T20:15:07.992Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> controller # [2638088.006444] controller data-mesher[233]: time=2026-10-04T20:15:07.992Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638088.007299] controller data-mesher[233]: time=2026-10-04T20:15:07.993Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638088.007382] controller data-mesher[233]: time=2026-10-04T20:15:07.993Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638088.007382] controller data-mesher[233]: time=2026-10-04T20:15:07.993Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638088.007382] controller data-mesher[233]: time=2026-10-04T20:15:07.993Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638088.007382] controller data-mesher[233]: time=2026-10-04T20:15:07.993Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> controller # [2638088.007520] controller data-mesher[233]: time=2026-10-04T20:15:07.993Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> controller # [2638088.007722] controller systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> controller # [2638088.010331] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...
container-test-run-dm-wireguard-star> controller # [2638088.077046] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [2638088.127027] controller data-mesher[233]: time=2026-10-04T20:15:08.112Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204
container-test-run-dm-wireguard-star> controller # [2638088.127897] controller dm-wg-star-publish[298]: Status: 204 No Content
container-test-run-dm-wireguard-star> controller # [2638088.133634] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.
container-test-run-dm-wireguard-star> controller # [2638088.134061] controller systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> controller # [2638088.171128] controller dm-wg-star-reconfig[325]: No peer data available yet, skipping
container-test-run-dm-wireguard-star> controller # [2638088.172058] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [2638088.204262] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> controller # [2638088.204711] controller systemd[1]: Startup finished in 14.832s.
container-test-run-dm-wireguard-star> peer1 # [2638088.007094] peer1 data-mesher[222]: time=2026-10-04T20:15:07.992Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer1 # [2638088.007094] peer1 data-mesher[222]: time=2026-10-04T20:15:07.992Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638088.007094] peer1 data-mesher[222]: time=2026-10-04T20:15:07.992Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638088.007094] peer1 data-mesher[222]: time=2026-10-04T20:15:07.992Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638088.007690] peer1 data-mesher[222]: time=2026-10-04T20:15:07.993Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638088.007690] peer1 data-mesher[222]: time=2026-10-04T20:15:07.993Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638088.007690] peer1 data-mesher[222]: time=2026-10-04T20:15:07.993Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer1 # [2638088.007980] peer1 data-mesher[222]: time=2026-10-04T20:15:07.993Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer1 # [2638088.007929] peer1 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer1 # [2638088.009831] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer1 # [2638088.156296] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [2638088.158108] peer1 data-mesher[222]: time=2026-10-04T20:15:08.143Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 status=204
container-test-run-dm-wireguard-star> peer1 # [2638088.158291] peer1 dm-wg-star-publish[287]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer1 # [2638088.162467] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [2638088.204209] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer1 # [2638088.204837] peer1 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer1 # [2638088.288619] peer1 dm-wg-star-reconfig[313]: No controller data available yet, skipping
container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 15.66 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/drwj5qfqx9axxgf0jvxlavglj43fr2kh-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/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [2638088.289594] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [2638088.289684] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [2638088.290047] peer1 systemd[1]: Startup finished in 14.920s.
container-test-run-dm-wireguard-star> controller # [2638093.008199] controller data-mesher[233]: time=2026-10-04T20:15:12.993Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [2638093.008626] controller data-mesher[233]: time=2026-10-04T20:15:12.994Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.994Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.994Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.994Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.995Z level=DEBUG msg="new file detected" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.995Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.995Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.995Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.995Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-04 20:15:08.136 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.995Z level=DEBUG msg="new file detected" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> controller # [2638093.009738] controller data-mesher[233]: time=2026-10-04T20:15:12.995Z level=INFO msg="received file request" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [2638093.014189] controller data-mesher[233]: time=2026-10-04T20:15:12.999Z level=INFO msg="file transfer complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [2638093.023528] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [2638093.077110] controller data-mesher[233]: time=2026-10-04T20:15:13.062Z level=INFO msg="download complete" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-04 20:15:08.136 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf written=true elapsed=67.58028ms
container-test-run-dm-wireguard-star> controller # [2638093.195331] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [2638093.195620] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.993Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.994Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.994Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.994Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.994Z level=DEBUG msg="new file detected" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.994Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.995Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-04 20:15:08.058 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638093.012038] peer1 data-mesher[222]: time=2026-10-04T20:15:12.995Z level=INFO msg="received file request" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> peer1 # [2638093.018275] peer1 data-mesher[222]: time=2026-10-04T20:15:13.003Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638093.018275] peer1 data-mesher[222]: time=2026-10-04T20:15:13.003Z level=DEBUG msg="new file detected" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [2638093.018275] peer1 data-mesher[222]: time=2026-10-04T20:15:13.003Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638093.018275] peer1 data-mesher[222]: time=2026-10-04T20:15:13.003Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [2638093.018275] peer1 data-mesher[222]: time=2026-10-04T20:15:13.003Z level=INFO msg="file transfer complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> peer1 # [2638093.021305] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [2638093.077467] peer1 data-mesher[222]: time=2026-10-04T20:15:13.063Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-04 20:15:08.058 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ written=true elapsed=68.239369ms
container-test-run-dm-wireguard-star> peer1 # [2638093.189544] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [2638093.189776] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.04 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q .
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q ., in 0.01 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: wg show wg-star peers | grep -q .
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: wg show wg-star peers | grep -q .
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::28bc:6cf0:1e15:8c64
container-test-run-dm-wireguard-star> controller # [2638098.010454] controller data-mesher[233]: time=2026-10-04T20:15:17.996Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [2638098.010454] controller data-mesher[233]: time=2026-10-04T20:15:17.996Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638098.011449] controller data-mesher[233]: time=2026-10-04T20:15:17.997Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638098.011678] controller data-mesher[233]: time=2026-10-04T20:15:17.997Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638098.011730] controller data-mesher[233]: time=2026-10-04T20:15:17.997Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638098.018391] controller data-mesher[233]: time=2026-10-04T20:15:18.004Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638098.018391] controller data-mesher[233]: time=2026-10-04T20:15:18.004Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer1 # [2638098.010986] peer1 data-mesher[222]: time=2026-10-04T20:15:17.996Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638098.010986] peer1 data-mesher[222]: time=2026-10-04T20:15:17.996Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638098.017907] peer1 data-mesher[222]: time=2026-10-04T20:15:18.003Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [2638098.017987] peer1 data-mesher[222]: time=2026-10-04T20:15:18.003Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638098.019050] peer1 data-mesher[222]: time=2026-10-04T20:15:18.004Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638098.019394] peer1 data-mesher[222]: time=2026-10-04T20:15:18.005Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638098.019430] peer1 data-mesher[222]: time=2026-10-04T20:15:18.005Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::28bc:6cf0:1e15:8c64, in 6.02 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::23fd:a719:7f48:87f1
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::23fd:a719:7f48:87f1, in 0.01 seconds)
container-test-run-dm-wireguard-star> controller: must succeed: wg show wg-star peers | wc -l
container-test-run-dm-wireguard-star> controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: systemd-nspawn running (pid 786)
container-test-run-dm-wireguard-star> peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...
container-test-run-dm-wireguard-star> peer2: waiting for unit data-mesher.service
container-test-run-dm-wireguard-star> nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-nspawn(peer2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-dm-wireguard-star> ░ Spawning container peer2 on /build/vm-state-peer2.
container-test-run-dm-wireguard-star> peer2 # No journal files were found.
container-test-run-dm-wireguard-star> peer2 # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> peer2 # [2638100.638837] peer2 systemd-journald[97]: Journal started
container-test-run-dm-wireguard-star> peer2 # [2638100.638899] peer2 systemd-journald[97]: Runtime Journal (/run/log/journal/83f269b630eb47719553d68c0a4aa92b) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> peer2 # [2638100.642028] peer2 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> peer2 # [2638100.657691] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> peer2 # [2638100.671834] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer2 # [2638100.672642] peer2 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> peer2 # [2638100.673311] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> peer2 # [2638100.683676] peer2 systemd-journald[97]: Time spent on flushing to /var/log/journal/83f269b630eb47719553d68c0a4aa92b is 1.574ms for 7 entries.
container-test-run-dm-wireguard-star> peer2 # [2638100.683676] peer2 systemd-journald[97]: System Journal (/var/log/journal/83f269b630eb47719553d68c0a4aa92b) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer2 # [2638100.705076] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer2 # [2638100.705617] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer2 # [2638100.706703] peer2 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [2638100.706798] peer2 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [2638100.707625] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer2 # [2638100.707673] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [2638100.708493] peer2 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer2 # [2638100.709179] peer2 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer2 # [2638100.709206] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer2 # [2638100.729579] peer2 systemd-tmpfiles[155]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [2638100.729761] peer2 systemd-tmpfiles[155]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [2638100.729882] peer2 systemd-tmpfiles[155]: fchmod() of /var/log/journal/83f269b630eb47719553d68c0a4aa92b failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [2638100.730063] peer2 systemd-tmpfiles[155]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [2638100.735447] peer2 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer2 # [2638100.737114] peer2 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer2 # [2638100.737833] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer2 # [2638100.754026] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer2 # [2638100.762033] peer2 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer2 # [2638100.763179] peer2 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer2 # [2638100.775175] peer2 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer2 # [2638100.814732] peer2 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer2 # [2638100.814937] peer2 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer2 # [2638100.815221] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer2 # [2638100.816589] peer2 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer2 # [2638100.897648] peer2 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer2 # [2638101.580734] peer2 systemd-networkd[214]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [2638101.580827] peer2 systemd-networkd[214]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [2638101.590818] peer2 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> peer2 # [2638101.590982] peer2 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> peer2 # [2638101.591120] peer2 systemd-networkd[214]: lo: Link UP
container-test-run-dm-wireguard-star> peer2 # [2638101.591125] peer2 systemd-networkd[214]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [2638101.591320] peer2 systemd-networkd[214]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer2 # [2638101.591671] peer2 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer2 # [2638101.592463] peer2 systemd-networkd[214]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer2 # [2638101.592748] peer2 systemd-networkd[214]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer2 # [2638101.592979] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer2 # [2638101.593219] peer2 systemd-networkd[214]: eth1: Link UP
container-test-run-dm-wireguard-star> peer2 # [2638101.593421] peer2 systemd-networkd[214]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [2638101.636583] peer2 systemd-networkd[214]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer2 # [2638101.636588] peer2 systemd-networkd[214]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [2638101.647629] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [2638101.649493] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer2 # [2638101.955494] peer2 systemd-resolved[130]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer2 # [2638101.955508] peer2 systemd-resolved[130]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer2 # [2638101.955511] peer2 systemd-resolved[130]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer2 # [2638101.955544] peer2 systemd-resolved[130]: 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 # [2638101.975825] peer2 systemd-resolved[130]: Using system hostname 'peer2'.
container-test-run-dm-wireguard-star> peer2 # [2638101.977499] peer2 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer2 # [2638101.977653] peer2 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer2 # [2638101.977773] peer2 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer2 # [2638101.977936] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [2638101.978004] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer2 # [2638101.978052] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [2638101.978089] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer2 # [2638101.978133] peer2 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer2 # [2638101.978203] peer2 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer2 # [2638101.978431] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer2 # [2638101.978652] peer2 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer2 # [2638101.978868] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer2 # [2638101.978915] peer2 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer2 # [2638101.978993] peer2 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer2 # [2638101.981133] peer2 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer2 # [2638101.982410] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer2 # [2638101.983861] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer2 # [2638101.985927] peer2 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer2 # [2638102.051199] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer2 # [2638102.224961] peer2 nsncd[222]: Oct 04 20:15:22.210 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 # [2638102.224891] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer2 # [2638102.224951] peer2 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [2638102.225010] peer2 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [2638102.226399] peer2 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer2 # [2638102.227258] peer2 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer2 # [2638102.267686] peer2 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer2 # [2638102.269349] peer2 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer2 # [2638102.269421] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer2 # [2638102.269460] peer2 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer2 # [2638102.358095] peer2 dbus-broker-launch[223]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer2 # [2638102.359926] peer2 dbus-broker-launch[223]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer2 # [2638102.359926] peer2 dbus-broker-launch[223]: Invalid user-name in /nix/store/l02zj79nnnv7h7qn3a3l5353m9faqb1g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer2 # [2638102.360409] peer2 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer2 # [2638102.367103] peer2 dbus-broker-launch[223]: Ready
container-test-run-dm-wireguard-star> peer2 # [2638102.688130] peer2 systemd-networkd[214]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer2 # [2638102.810085] peer2 data-mesher[220]: time=2026-10-04T20:15:22.795Z 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 # [2638102.811151] peer2 data-mesher[220]: time=2026-10-04T20:15:22.796Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ: [/dns/controller.clan/tcp/7946]} {12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf: [/dns/peer1.clan/tcp/7946]} {12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> peer2 # [2638102.811151] peer2 data-mesher[220]: time=2026-10-04T20:15:22.796Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer2 # [2638102.825106] peer2 data-mesher[220]: time=2026-10-04T20:15:22.810Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer2 # [2638102.825795] peer2 data-mesher[220]: time=2026-10-04T20:15:22.811Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer2 # [2638102.831576] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="libp2p host created" peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK 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::c289:fe9d:a6bc:e45b/tcp/7946]"
container-test-run-dm-wireguard-star> peer2 # [2638102.831685] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer2 # [2638102.831685] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [2638102.831685] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [2638102.831685] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer2 # [2638102.831887] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer2 # [2638102.831887] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer2 # [2638102.831887] peer2 data-mesher[220]: time=2026-10-04T20:15:22.817Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer2 # [2638102.839732] peer2 data-mesher[220]: time=2026-10-04T20:15:22.825Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [2638102.847767] peer2 data-mesher[220]: time=2026-10-04T20:15:22.833Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [2638102.841468] peer1 data-mesher[222]: time=2026-10-04T20:15:22.827Z level=INFO msg="peer connected" peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [2638103.012729] peer1 data-mesher[222]: time=2026-10-04T20:15:22.998Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638103.012729] peer1 data-mesher[222]: time=2026-10-04T20:15:22.998Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638103.019501] peer1 data-mesher[222]: time=2026-10-04T20:15:23.005Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [2638103.019579] peer1 data-mesher[222]: time=2026-10-04T20:15:23.005Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638103.020938] peer1 data-mesher[222]: time=2026-10-04T20:15:23.006Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638103.021510] peer1 data-mesher[222]: time=2026-10-04T20:15:23.007Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638103.021581] peer1 data-mesher[222]: time=2026-10-04T20:15:23.007Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638102.849284] controller data-mesher[233]: time=2026-10-04T20:15:22.835Z level=INFO msg="peer connected" peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> controller # [2638103.012131] controller data-mesher[233]: time=2026-10-04T20:15:22.997Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [2638103.012131] controller data-mesher[233]: time=2026-10-04T20:15:22.997Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638103.013691] controller data-mesher[233]: time=2026-10-04T20:15:22.999Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638103.014303] controller data-mesher[233]: time=2026-10-04T20:15:23.000Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> controller # [2638103.014370] controller data-mesher[233]: time=2026-10-04T20:15:23.000Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638103.019971] controller data-mesher[233]: time=2026-10-04T20:15:23.005Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638103.020075] controller data-mesher[233]: time=2026-10-04T20:15:23.005Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer2 # [2638103.031957] peer2 systemd-logind[239]: New seat seat0.
container-test-run-dm-wireguard-star> peer2 # [2638103.032233] peer2 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer2 # [2638103.033388] peer2 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer2 # [2638103.090474] peer2 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [2638103.090663] peer2 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller # [2638108.014751] controller data-mesher[233]: time=2026-10-04T20:15:28.000Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [2638108.015504] controller data-mesher[233]: time=2026-10-04T20:15:28.000Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> controller # [2638108.016711] controller data-mesher[233]: time=2026-10-04T20:15:28.002Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> controller # [2638108.016711] controller data-mesher[233]: time=2026-10-04T20:15:28.002Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> controller # [2638108.016850] controller data-mesher[233]: time=2026-10-04T20:15:28.002Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638108.017142] controller data-mesher[233]: time=2026-10-04T20:15:28.002Z level=INFO msg="received file request" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [2638108.017359] controller data-mesher[233]: time=2026-10-04T20:15:28.003Z level=INFO msg="received file request" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> controller # [2638108.019412] controller data-mesher[233]: time=2026-10-04T20:15:28.005Z level=INFO msg="file transfer complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [2638108.021186] controller data-mesher[233]: time=2026-10-04T20:15:28.006Z level=INFO msg="file transfer complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> controller # [2638108.023302] controller data-mesher[233]: time=2026-10-04T20:15:28.009Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638108.023402] controller data-mesher[233]: time=2026-10-04T20:15:28.009Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer2 # [2638108.015668] peer2 data-mesher[220]: time=2026-10-04T20:15:28.001Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638108.016122] peer2 data-mesher[220]: time=2026-10-04T20:15:28.001Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638108.016363] peer2 data-mesher[220]: time=2026-10-04T20:15:28.002Z level=DEBUG msg="new file detected" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> peer2 # [2638108.016363] peer2 data-mesher[220]: time=2026-10-04T20:15:28.002Z level=DEBUG msg="new file detected" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [2638108.016565] peer2 data-mesher[220]: time=2026-10-04T20:15:28.002Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4
container-test-run-dm-wireguard-star> peer2 # [2638108.016565] peer2 data-mesher[220]: time=2026-10-04T20:15:28.002Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [2638108.016673] peer2 data-mesher[220]: time=2026-10-04T20:15:28.002Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-04 20:15:08.058 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638108.017371] peer2 data-mesher[220]: time=2026-10-04T20:15:28.002Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-04 20:15:08.136 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638108.024334] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [2638108.031702] peer2 data-mesher[220]: time=2026-10-04T20:15:28.017Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-04 20:15:08.058 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ written=true elapsed=15.115646ms
container-test-run-dm-wireguard-star> peer2 # [2638108.032822] peer2 data-mesher[220]: time=2026-10-04T20:15:28.018Z level=INFO msg="download complete" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-04 20:15:08.136 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ written=true elapsed=16.020898ms
container-test-run-dm-wireguard-star> peer2 # [2638108.210645] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [2638108.211014] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [2638108.022581] peer1 data-mesher[222]: time=2026-10-04T20:15:28.008Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [2638108.023307] peer1 data-mesher[222]: time=2026-10-04T20:15:28.008Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638108.024343] peer1 data-mesher[222]: time=2026-10-04T20:15:28.010Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638108.024928] peer1 data-mesher[222]: time=2026-10-04T20:15:28.010Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638108.025011] peer1 data-mesher[222]: time=2026-10-04T20:15:28.010Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state...
container-test-run-dm-wireguard-star> peer2 # [2638112.832834] peer2 data-mesher[220]: time=2026-10-04T20:15:32.818Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer2 # [2638112.832834] peer2 data-mesher[220]: time=2026-10-04T20:15:32.818Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> peer2 # [2638112.834605] peer2 data-mesher[220]: time=2026-10-04T20:15:32.820Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer2 # [2638112.835203] peer2 data-mesher[220]: time=2026-10-04T20:15:32.821Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s
container-test-run-dm-wireguard-star> peer2 # [2638112.835291] peer2 data-mesher[220]: time=2026-10-04T20:15:32.821Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer2 # [2638112.835436] peer2 data-mesher[220]: time=2026-10-04T20:15:32.821Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer2 # [2638112.835717] peer2 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer2 # [2638112.837912] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer2 # [2638113.000747] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [2638113.018176] peer2 data-mesher[220]: time=2026-10-04T20:15:33.003Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638113.018221] peer2 data-mesher[220]: time=2026-10-04T20:15:33.004Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638112.833556] peer1 data-mesher[222]: time=2026-10-04T20:15:32.819Z level=INFO msg="received state sync from peer" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> peer1 # [2638112.833556] peer1 data-mesher[222]: time=2026-10-04T20:15:32.819Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> peer1 # [2638113.025335] peer1 data-mesher[222]: time=2026-10-04T20:15:33.011Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [2638113.025473] peer1 data-mesher[222]: time=2026-10-04T20:15:33.011Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638113.026891] peer1 data-mesher[222]: time=2026-10-04T20:15:33.012Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer1 # [2638113.027468] peer1 data-mesher[222]: time=2026-10-04T20:15:33.013Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638113.027536] peer1 data-mesher[222]: time=2026-10-04T20:15:33.013Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638113.017598] controller data-mesher[233]: time=2026-10-04T20:15:33.003Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [2638113.017598] controller data-mesher[233]: time=2026-10-04T20:15:33.003Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> controller # [2638113.018894] controller data-mesher[233]: time=2026-10-04T20:15:33.004Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> controller # [2638113.019495] controller data-mesher[233]: time=2026-10-04T20:15:33.005Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> controller # [2638113.019560] controller data-mesher[233]: time=2026-10-04T20:15:33.005Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638113.025909] controller data-mesher[233]: time=2026-10-04T20:15:33.011Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> controller # [2638113.025909] controller data-mesher[233]: time=2026-10-04T20:15:33.011Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer2 # [2638113.160125] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [2638113.160514] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer2: (finished: waiting for unit data-mesher.service, in 14.17 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/drwj5qfqx9axxgf0jvxlavglj43fr2kh-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/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> peer2 # [2638113.596280] peer2 data-mesher[220]: time=2026-10-04T20:15:33.582Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY status=204
container-test-run-dm-wireguard-star> peer2 # [2638113.596508] peer2 dm-wg-star-publish[297]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer2 # [2638113.602393] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [2638113.602730] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer2 # [2638113.603713] peer2 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer2 # [2638113.620297] peer2 systemd[1]: Startup finished in 13.413s.
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 4.06 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1
container-test-run-dm-wireguard-star> controller # [2638117.838116] controller data-mesher[233]: time=2026-10-04T20:15:37.823Z level=INFO msg="received state sync from peer" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> controller # [2638117.838116] controller data-mesher[233]: time=2026-10-04T20:15:37.823Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> controller # [2638117.839088] controller data-mesher[233]: time=2026-10-04T20:15:37.824Z level=DEBUG msg="new file detected" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> controller # [2638117.839088] controller data-mesher[233]: time=2026-10-04T20:15:37.824Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> controller # [2638117.839088] controller data-mesher[233]: time=2026-10-04T20:15:37.824Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-04 20:15:32.981 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> controller # [2638117.845793] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [2638118.010360] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [2638118.010755] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> controller # [2638118.019851] controller data-mesher[233]: time=2026-10-04T20:15:38.005Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [2638118.019936] controller data-mesher[233]: time=2026-10-04T20:15:38.005Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> controller # [2638118.021197] controller data-mesher[233]: time=2026-10-04T20:15:38.007Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> controller # [2638118.021762] controller data-mesher[233]: time=2026-10-04T20:15:38.007Z level=DEBUG msg="new file detected" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> controller # [2638118.022362] controller data-mesher[233]: time=2026-10-04T20:15:38.008Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> controller # [2638118.022439] controller data-mesher[233]: time=2026-10-04T20:15:38.008Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [2638118.120140] controller data-mesher[233]: time=2026-10-04T20:15:38.105Z level=INFO msg="download complete" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-04 20:15:32.981 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK written=true elapsed=281.098787ms
container-test-run-dm-wireguard-star> peer2 # [2638117.836439] peer2 data-mesher[220]: time=2026-10-04T20:15:37.822Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-dm-wireguard-star> peer2 # [2638117.837112] peer2 data-mesher[220]: time=2026-10-04T20:15:37.822Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer2 # [2638117.839532] peer2 data-mesher[220]: time=2026-10-04T20:15:37.825Z level=INFO msg="received file request" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> peer2 # [2638117.839741] peer2 data-mesher[220]: time=2026-10-04T20:15:37.825Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638117.840199] peer2 data-mesher[220]: time=2026-10-04T20:15:37.826Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s
container-test-run-dm-wireguard-star> peer2 # [2638117.840262] peer2 data-mesher[220]: time=2026-10-04T20:15:37.826Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [2638117.840858] peer2 data-mesher[220]: time=2026-10-04T20:15:37.826Z level=INFO msg="file transfer complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> peer2 # [2638118.020455] peer2 data-mesher[220]: time=2026-10-04T20:15:38.006Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638118.020455] peer2 data-mesher[220]: time=2026-10-04T20:15:38.006Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ
container-test-run-dm-wireguard-star> peer2 # [2638118.029202] peer2 data-mesher[220]: time=2026-10-04T20:15:38.015Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer2 # [2638118.029202] peer2 data-mesher[220]: time=2026-10-04T20:15:38.015Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf
container-test-run-dm-wireguard-star> peer2 # [2638118.031416] peer2 data-mesher[220]: time=2026-10-04T20:15:38.017Z level=INFO msg="received file request" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> peer2 # [2638118.033995] peer2 data-mesher[220]: time=2026-10-04T20:15:38.019Z level=INFO msg="file transfer complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> peer1 # [2638118.028570] peer1 data-mesher[222]: time=2026-10-04T20:15:38.014Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [2638118.029032] peer1 data-mesher[222]: time=2026-10-04T20:15:38.014Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638118.030191] peer1 data-mesher[222]: time=2026-10-04T20:15:38.015Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> peer1 # [2638118.030745] peer1 data-mesher[222]: time=2026-10-04T20:15:38.016Z level=DEBUG msg="new file detected" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> peer1 # [2638118.030893] peer1 data-mesher[222]: time=2026-10-04T20:15:38.016Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY
container-test-run-dm-wireguard-star> peer1 # [2638118.030972] peer1 data-mesher[222]: time=2026-10-04T20:15:38.016Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-04 20:15:32.981 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK
container-test-run-dm-wireguard-star> peer1 # [2638118.031463] peer1 data-mesher[222]: time=2026-10-04T20:15:38.017Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s
container-test-run-dm-wireguard-star> peer1 # [2638118.031463] peer1 data-mesher[222]: time=2026-10-04T20:15:38.017Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [2638118.039204] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [2638118.127644] peer1 data-mesher[222]: time=2026-10-04T20:15:38.113Z level=INFO msg="download complete" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-04 20:15:32.981 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK written=true elapsed=96.687997ms
container-test-run-dm-wireguard-star> peer1 # [2638118.239374] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [2638118.239710] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 1.04 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::28bc:6cf0:1e15:8c64
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::28bc:6cf0:1e15:8c64, in 0.01 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c289:fe9d:a6bc:e45b
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c289:fe9d:a6bc:e45b, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c289:fe9d:a6bc:e45b
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c289:fe9d:a6bc:e45b, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::23fd:a719:7f48:87f1
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::23fd:a719:7f48:87f1, in 0.01 seconds)
container-test-run-dm-wireguard-star> (finished: run the VM test script, in 46.12 seconds)
container-test-run-dm-wireguard-star> test script finished in 46.41s
container-test-run-dm-wireguard-star> cleanup
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 50)
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 53)
container-test-run-dm-wireguard-star> Container controller terminated by signal KILL.
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 786)
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.64 seconds)