don't know how to build these paths: /nix/store/md54z127jq2zk3cn5k8xgjz3x7i9az1s-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 ilaf5k2fn0j6r6pa7jvqcq2022k5zawj-system-path.drv: build01: not scheduled here: ilaf5k2fn0j6r6pa7jvqcq2022k5zawj-system-path.drv, asking the scheduler again building '/nix/store/ilaf5k2fn0j6r6pa7jvqcq2022k5zawj-system-path.drv' on nix-store.geninf.io:50051 system-path> build01: building ilaf5k2fn0j6r6pa7jvqcq2022k5zawj-system-path.drv system-path> structuredAttrs is enabled system-path> created 1724 symlinks in user environment lav87cl9nm6dac4yzkxkip60kmm0ss99-dbus-1.drv: build01: not scheduled here: lav87cl9nm6dac4yzkxkip60kmm0ss99-dbus-1.drv, asking the scheduler again building '/nix/store/lav87cl9nm6dac4yzkxkip60kmm0ss99-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> build01: building lav87cl9nm6dac4yzkxkip60kmm0ss99-dbus-1.drv 0b3m957p2j55r7wrf3j0hdys0if15kkd-X-Restart-Triggers-dbus-broker.drv: build01: not scheduled here: 0b3m957p2j55r7wrf3j0hdys0if15kkd-X-Restart-Triggers-dbus-broker.drv, asking the scheduler again building '/nix/store/0b3m957p2j55r7wrf3j0hdys0if15kkd-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> build01: building 0b3m957p2j55r7wrf3j0hdys0if15kkd-X-Restart-Triggers-dbus-broker.drv X-Restart-Triggers-dbus-broker> structuredAttrs is enabled d1bb5d64xsiwlp683bay0lbhqcvl06xc-unit-dbus-broker.service.drv: build01: not scheduled here: d1bb5d64xsiwlp683bay0lbhqcvl06xc-unit-dbus-broker.service.drv, asking the scheduler again gm0b1vngmy53n2fahibgpgbxix06zi7i-unit-dbus-broker.service.drv: build01: not scheduled here: gm0b1vngmy53n2fahibgpgbxix06zi7i-unit-dbus-broker.service.drv, asking the scheduler again building '/nix/store/d1bb5d64xsiwlp683bay0lbhqcvl06xc-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building d1bb5d64xsiwlp683bay0lbhqcvl06xc-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/gm0b1vngmy53n2fahibgpgbxix06zi7i-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building gm0b1vngmy53n2fahibgpgbxix06zi7i-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled n8b09x0qpsc0pkkzf2gwzyhjy2cfpwvl-system-units.drv: build01: not scheduled here: n8b09x0qpsc0pkkzf2gwzyhjy2cfpwvl-system-units.drv, asking the scheduler again building '/nix/store/n8b09x0qpsc0pkkzf2gwzyhjy2cfpwvl-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building n8b09x0qpsc0pkkzf2gwzyhjy2cfpwvl-system-units.drv ijdq352jkwbvafihi5rh50xv1z6np6v1-system-units.drv: build01: not scheduled here: ijdq352jkwbvafihi5rh50xv1z6np6v1-system-units.drv, asking the scheduler again building '/nix/store/ijdq352jkwbvafihi5rh50xv1z6np6v1-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building ijdq352jkwbvafihi5rh50xv1z6np6v1-system-units.drv 05njlzczx3n7rxzjbybw7p5wcrazhm9m-system-units.drv: build01: not scheduled here: 05njlzczx3n7rxzjbybw7p5wcrazhm9m-system-units.drv, asking the scheduler again building '/nix/store/05njlzczx3n7rxzjbybw7p5wcrazhm9m-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building 05njlzczx3n7rxzjbybw7p5wcrazhm9m-system-units.drv fhxliri7jn22nb2y6w22gs1lnliljmn1-user-units.drv: build01: not scheduled here: fhxliri7jn22nb2y6w22gs1lnliljmn1-user-units.drv, asking the scheduler again building '/nix/store/fhxliri7jn22nb2y6w22gs1lnliljmn1-user-units.drv' on nix-store.geninf.io:50051 user-units> build01: building fhxliri7jn22nb2y6w22gs1lnliljmn1-user-units.drv building '/nix/store/r07wly1b1nx8smhc73vw7b4gshgh4snj-etc.drv' on nix-store.geninf.io:50051 etc> build01: building r07wly1b1nx8smhc73vw7b4gshgh4snj-etc.drv building '/nix/store/m59m5q8l1g43hf511l99bs1akmx6dm89-etc.drv' on nix-store.geninf.io:50051 etc> build01: building m59m5q8l1g43hf511l99bs1akmx6dm89-etc.drv building '/nix/store/0yzgizd8frki28myy17dsaycjn1q1lfw-etc.drv' on nix-store.geninf.io:50051 etc> build01: building 0yzgizd8frki28myy17dsaycjn1q1lfw-etc.drv building '/nix/store/f1jbkq8984xfjq0drcbvxyzfwpcx5sjb-activate.drv' on nix-store.geninf.io:50051 activate> build01: building f1jbkq8984xfjq0drcbvxyzfwpcx5sjb-activate.drv activate> structuredAttrs is enabled building '/nix/store/kf2an24wk4gar2f56xy03mikhkqr8g40-activate.drv' on nix-store.geninf.io:50051 activate> build01: building kf2an24wk4gar2f56xy03mikhkqr8g40-activate.drv activate> structuredAttrs is enabled building '/nix/store/a4i64m1ag5m4fb4xrfj696d5sqhg34px-activate.drv' on nix-store.geninf.io:50051 activate> build01: building a4i64m1ag5m4fb4xrfj696d5sqhg34px-activate.drv activate> structuredAttrs is enabled building '/nix/store/iqf1y2v1rivqklxi3lbfg84fqp0czyk6-nixos-system-controller-test.drv' on nix-store.geninf.io:50051 nixos-system-controller-test> build01: building iqf1y2v1rivqklxi3lbfg84fqp0czyk6-nixos-system-controller-test.drv nixos-system-controller-test> structuredAttrs is enabled building '/nix/store/lsyhyn51bdvnl85bsicra59783p5bqlp-nixos-system-peer2-test.drv' on nix-store.geninf.io:50051 nixos-system-peer2-test> build01: building lsyhyn51bdvnl85bsicra59783p5bqlp-nixos-system-peer2-test.drv nixos-system-peer2-test> structuredAttrs is enabled building '/nix/store/jn9bpsrmkvyz8bkg2qqfm7fy044yfznp-nixos-system-peer1-test.drv' on nix-store.geninf.io:50051 nixos-system-peer1-test> build01: building jn9bpsrmkvyz8bkg2qqfm7fy044yfznp-nixos-system-peer1-test.drv nixos-system-peer1-test> structuredAttrs is enabled building '/nix/store/94nn320zacvfa1bh8zfhd3xldz2wvx6j-run-controller-nspawn.drv' on nix-store.geninf.io:50051 run-controller-nspawn> build01: building 94nn320zacvfa1bh8zfhd3xldz2wvx6j-run-controller-nspawn.drv building '/nix/store/2d9nygxp9k28irn4qjx1gkb1lf56p6wr-run-peer2-nspawn.drv' on nix-store.geninf.io:50051 run-peer2-nspawn> build01: building 2d9nygxp9k28irn4qjx1gkb1lf56p6wr-run-peer2-nspawn.drv building '/nix/store/km0gmkwypf1859l9jvaz653sjpsy6hg9-run-peer1-nspawn.drv' on nix-store.geninf.io:50051 run-peer1-nspawn> build01: building km0gmkwypf1859l9jvaz653sjpsy6hg9-run-peer1-nspawn.drv building '/nix/store/f4kqf3d69k3b0i3lw0a4g5020mvc4rj0-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> build01: building f4kqf3d69k3b0i3lw0a4g5020mvc4rj0-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled building '/nix/store/d0y0bc8az5n9k08nrivksh1wa9awvvax-nixos-test-driver-dm-wireguard-star.drv' on nix-store.geninf.io:50051 nixos-test-driver-dm-wireguard-star> build01: building d0y0bc8az5n9k08nrivksh1wa9awvvax-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/md54z127jq2zk3cn5k8xgjz3x7i9az1s-container-test-run-dm-wireguard-star.drv' on nix-store.geninf.io:50051 container-test-run-dm-wireguard-star> build01: building md54z127jq2zk3cn5k8xgjz3x7i9az1s-container-test-run-dm-wireguard-star.drv container-test-run-dm-wireguard-star> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-dm-wireguard-star> start all VLans container-test-run-dm-wireguard-star> (finished: start all VLans, in 0.00 seconds) container-test-run-dm-wireguard-star> container-test-run-dm-wireguard-star> Test will time out and terminate in 3600.0 seconds container-test-run-dm-wireguard-star> run the VM test script container-test-run-dm-wireguard-star> additionally exposed symbols: container-test-run-dm-wireguard-star> controller, peer1, peer2, container-test-run-dm-wireguard-star> vlan1, container-test-run-dm-wireguard-star> start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh container-test-run-dm-wireguard-star> controller: systemd-nspawn running (pid 50) container-test-run-dm-wireguard-star> controller: Waiting for journal at /build/vm-state-controller/var/log/journal... container-test-run-dm-wireguard-star> peer1: systemd-nspawn running (pid 53) container-test-run-dm-wireguard-star> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal... container-test-run-dm-wireguard-star> controller: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(controller): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-dm-wireguard-star> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-dm-wireguard-star> ░ Spawning container controller on /build/vm-state-controller. container-test-run-dm-wireguard-star> ░ Spawning container peer1 on /build/vm-state-peer1. 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 # [2707567.945663] controller systemd-journald[108]: Journal started container-test-run-dm-wireguard-star> peer1 # [2707567.961006] peer1 systemd-journald[99]: Journal started container-test-run-dm-wireguard-star> controller # [2707567.945726] controller systemd-journald[108]: Runtime Journal (/run/log/journal/d446c54290b84788976075ace9d7e3d2) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> peer1 # [2707567.961067] peer1 systemd-journald[99]: Runtime Journal (/run/log/journal/4a16a3c6a1a74594883bfbca5e65dae2) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> controller # [2707567.949604] controller systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> peer1 # [2707567.966893] peer1 systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> peer1 # [2707567.978885] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer1 # [2707567.994256] peer1 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer1 # [2707567.995129] peer1 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer1 # [2707567.995810] peer1 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer1 # [2707568.003628] peer1 systemd-journald[99]: Time spent on flushing to /var/log/journal/4a16a3c6a1a74594883bfbca5e65dae2 is 1.535ms for 7 entries. container-test-run-dm-wireguard-star> peer1 # [2707568.003628] peer1 systemd-journald[99]: System Journal (/var/log/journal/4a16a3c6a1a74594883bfbca5e65dae2) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer1 # [2707568.013151] peer1 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer1 # [2707568.013616] peer1 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer1 # [2707568.014738] peer1 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2707568.014833] peer1 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2707568.015686] peer1 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer1 # [2707568.015735] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [2707567.962072] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer1 # [2707568.016541] peer1 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> controller # [2707567.975858] controller systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer1 # [2707568.017211] peer1 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> controller # [2707567.976652] controller systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer1 # [2707568.017244] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> controller # [2707567.977253] controller systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer1 # [2707568.035429] peer1 systemd-tmpfiles[149]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2707567.987465] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/d446c54290b84788976075ace9d7e3d2 is 2.677ms for 7 entries. container-test-run-dm-wireguard-star> peer1 # [2707568.035626] peer1 systemd-tmpfiles[149]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2707567.987465] controller systemd-journald[108]: System Journal (/var/log/journal/d446c54290b84788976075ace9d7e3d2) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer1 # [2707568.035759] peer1 systemd-tmpfiles[149]: fchmod() of /var/log/journal/4a16a3c6a1a74594883bfbca5e65dae2 failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2707567.994276] controller systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer1 # [2707568.035956] peer1 systemd-tmpfiles[149]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2707567.994870] controller systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2707568.037376] peer1 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> controller # [2707567.994977] controller systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2707568.038378] peer1 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> controller # [2707567.995834] controller systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer1 # [2707568.039101] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> controller # [2707567.995880] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [2707568.051096] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> controller # [2707567.996696] controller systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer1 # [2707568.058250] peer1 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> controller # [2707567.996728] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer1 # [2707568.059336] peer1 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> controller # [2707567.998137] controller systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer1 # [2707568.068235] peer1 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> controller # [2707567.999252] controller systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer1 # [2707568.070122] peer1 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> controller # [2707568.017113] controller systemd-tmpfiles[159]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2707568.164304] peer1 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> controller # [2707568.017311] controller systemd-tmpfiles[159]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2707568.164505] peer1 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> controller # [2707568.017452] controller systemd-tmpfiles[159]: fchmod() of /var/log/journal/d446c54290b84788976075ace9d7e3d2 failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2707568.164732] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> controller # [2707568.017656] controller systemd-tmpfiles[159]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2707568.165746] peer1 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> controller # [2707568.019063] controller systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> controller # [2707568.020492] controller systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> controller # [2707568.021231] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> controller # [2707568.033735] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> controller # [2707568.039464] controller systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> controller # [2707568.040540] controller systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> controller # [2707568.050337] controller systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> controller # [2707568.068241] controller systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> controller # [2707568.117069] controller systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> controller # [2707568.117223] controller systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> controller # [2707568.117438] controller systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> controller # [2707568.118486] controller systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> controller # [2707568.888580] controller systemd-networkd[228]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2707568.888673] controller systemd-networkd[228]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2707568.929632] controller systemd-networkd[228]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> controller # [2707568.929897] controller systemd-networkd[228]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> controller # [2707568.930041] controller systemd-networkd[228]: lo: Link UP container-test-run-dm-wireguard-star> controller # [2707568.930045] controller systemd-networkd[228]: lo: Gained carrier container-test-run-dm-wireguard-star> controller # [2707568.930247] controller systemd-networkd[228]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> controller # [2707568.931438] controller systemd-networkd[228]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> controller # [2707568.931936] controller systemd-networkd[228]: wg-star: netdev ready container-test-run-dm-wireguard-star> controller # [2707568.932401] controller systemd-networkd[228]: eth1: Link UP container-test-run-dm-wireguard-star> controller # [2707568.932671] controller systemd-networkd[228]: eth1: Gained carrier container-test-run-dm-wireguard-star> controller # [2707568.953699] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2707568.954576] controller systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> controller # [2707568.980883] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> controller # [2707568.983153] controller systemd-networkd[228]: wg-star: Link UP container-test-run-dm-wireguard-star> controller # [2707568.983158] controller systemd-networkd[228]: wg-star: Gained carrier container-test-run-dm-wireguard-star> controller # [2707568.993448] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer1 # [2707568.928338] peer1 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2707568.928437] peer1 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2707568.947664] peer1 systemd-networkd[217]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer1 # [2707568.947844] peer1 systemd-networkd[217]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer1 # [2707568.947993] peer1 systemd-networkd[217]: lo: Link UP container-test-run-dm-wireguard-star> peer1 # [2707568.947999] peer1 systemd-networkd[217]: lo: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2707568.948199] peer1 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer1 # [2707568.953513] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2707568.954242] peer1 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer1 # [2707568.980778] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer1 # [2707568.983093] peer1 systemd-networkd[217]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer1 # [2707568.983456] peer1 systemd-networkd[217]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer1 # [2707568.983972] peer1 systemd-networkd[217]: eth1: Link UP container-test-run-dm-wireguard-star> peer1 # [2707568.984224] peer1 systemd-networkd[217]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2707569.032844] peer1 systemd-networkd[217]: wg-star: Link UP container-test-run-dm-wireguard-star> peer1 # [2707569.032849] peer1 systemd-networkd[217]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2707569.034798] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer1 # [2707569.496142] peer1 systemd-resolved[131]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer1 # [2707569.496154] peer1 systemd-resolved[131]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer1 # [2707569.496157] peer1 systemd-resolved[131]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer1 # [2707569.496193] peer1 systemd-resolved[131]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test container-test-run-dm-wireguard-star> peer1 # [2707569.516692] peer1 systemd-resolved[131]: Using system hostname 'peer1'. container-test-run-dm-wireguard-star> peer1 # [2707569.518251] peer1 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> peer1 # [2707569.518358] peer1 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> peer1 # [2707569.518439] peer1 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> peer1 # [2707569.518543] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2707569.518585] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> peer1 # [2707569.518607] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [2707569.518632] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> peer1 # [2707569.518657] peer1 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> peer1 # [2707569.518698] peer1 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> peer1 # [2707569.518839] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> peer1 # [2707569.518984] peer1 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> peer1 # [2707569.519117] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> peer1 # [2707569.519144] peer1 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> peer1 # [2707569.519196] peer1 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> peer1 # [2707569.544560] peer1 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> peer1 # [2707569.545538] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> peer1 # [2707569.546604] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> peer1 # [2707569.548182] peer1 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> peer1 # [2707569.573187] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> peer1 # [2707569.741015] peer1 nsncd[224]: Oct 05 15:33:09.726 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 # [2707569.741098] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> peer1 # [2707569.741203] peer1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> peer1 # [2707569.741326] peer1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer1 # [2707569.745902] peer1 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> peer1 # [2707569.747384] peer1 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> controller # [2707569.461065] controller systemd-resolved[142]: Positive Trust Anchors: container-test-run-dm-wireguard-star> controller # [2707569.461075] controller systemd-resolved[142]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> controller # [2707569.461080] controller systemd-resolved[142]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> controller # [2707569.461118] controller systemd-resolved[142]: 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 # [2707569.481776] controller systemd-resolved[142]: Using system hostname 'controller'. container-test-run-dm-wireguard-star> controller # [2707569.484188] controller systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> controller # [2707569.484298] controller systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> controller # [2707569.484398] controller systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> controller # [2707569.484507] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher. container-test-run-dm-wireguard-star> controller # [2707569.484554] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [2707569.484586] controller systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> controller # [2707569.484612] controller systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> controller # [2707569.484655] controller systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> controller # [2707569.484802] controller systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> controller # [2707569.484949] controller systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> controller # [2707569.485095] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> controller # [2707569.485123] controller systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> controller # [2707569.485179] controller systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> controller # [2707569.486814] controller systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> controller # [2707569.487751] controller systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> controller # [2707569.489013] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> controller # [2707569.490448] controller systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> controller # [2707569.562377] controller systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> controller # [2707569.761207] controller systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> controller # [2707569.762553] controller nsncd[235]: Oct 05 15:33:09.747 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 # [2707569.761298] controller systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> controller # [2707569.761358] controller systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer1 # [2707569.759318] peer1 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> peer1 # [2707569.767026] peer1 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> peer1 # [2707569.767245] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> peer1 # [2707569.767836] peer1 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> controller # [2707569.767327] controller systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> controller # [2707569.768767] controller systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> controller # [2707569.781395] controller systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> controller # [2707569.782740] controller systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> controller # [2707569.782804] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> controller # [2707569.782823] controller systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> peer1 # [2707570.048548] peer1 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> peer1 # [2707570.049546] peer1 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> peer1 # [2707570.049546] peer1 dbus-broker-launch[225]: Invalid user-name in /nix/store/76bba54qlpzlasgg0vwbcr9xfw1qjrn7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> peer1 # [2707570.050647] peer1 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> peer1 # [2707570.057605] peer1 dbus-broker-launch[225]: Ready container-test-run-dm-wireguard-star> controller # [2707570.077099] controller dbus-broker-launch[236]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> controller # [2707570.077853] controller dbus-broker-launch[236]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> controller # [2707570.077853] controller dbus-broker-launch[236]: Invalid user-name in /nix/store/76bba54qlpzlasgg0vwbcr9xfw1qjrn7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> controller # [2707570.078285] controller systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> controller # [2707570.085593] controller dbus-broker-launch[236]: Ready container-test-run-dm-wireguard-star> controller # [2707570.464257] controller systemd-networkd[228]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> controller # [2707570.551329] controller data-mesher[233]: time=2026-10-05T15:33:10.537Z 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 # [2707570.552476] controller data-mesher[233]: time=2026-10-05T15:33:10.538Z 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 # [2707570.552476] controller data-mesher[233]: time=2026-10-05T15:33:10.538Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> controller # [2707570.638644] controller data-mesher[233]: time=2026-10-05T15:33:10.624Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> controller # [2707570.638869] controller data-mesher[233]: time=2026-10-05T15:33:10.624Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> controller # [2707570.642993] controller data-mesher[233]: time=2026-10-05T15:33:10.628Z 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 # [2707570.643081] controller data-mesher[233]: time=2026-10-05T15:33:10.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> controller # [2707570.643081] controller data-mesher[233]: time=2026-10-05T15:33:10.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> controller # [2707570.643081] controller data-mesher[233]: time=2026-10-05T15:33:10.628Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> controller # [2707570.643081] controller data-mesher[233]: time=2026-10-05T15:33:10.628Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> controller # [2707570.643165] controller data-mesher[233]: time=2026-10-05T15:33:10.628Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> controller # [2707570.643215] controller data-mesher[233]: time=2026-10-05T15:33:10.629Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> controller # [2707570.643251] controller data-mesher[233]: time=2026-10-05T15:33:10.629Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> controller # [2707570.817839] controller systemd-logind[249]: New seat seat0. container-test-run-dm-wireguard-star> controller # [2707570.818158] controller systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer1 # [2707570.601744] peer1 data-mesher[222]: time=2026-10-05T15:33:10.587Z 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 # [2707570.602857] peer1 data-mesher[222]: time=2026-10-05T15:33:10.588Z 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 # [2707570.602857] peer1 data-mesher[222]: time=2026-10-05T15:33:10.588Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer1 # [2707570.639030] peer1 data-mesher[222]: time=2026-10-05T15:33:10.624Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer1 # [2707570.639030] peer1 data-mesher[222]: time=2026-10-05T15:33:10.624Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer1 # [2707570.642967] peer1 data-mesher[222]: time=2026-10-05T15:33:10.628Z 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 # [2707570.643130] peer1 data-mesher[222]: time=2026-10-05T15:33:10.628Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer1 # [2707570.643130] peer1 data-mesher[222]: time=2026-10-05T15:33:10.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer1 # [2707570.643130] peer1 data-mesher[222]: time=2026-10-05T15:33:10.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer1 # [2707570.643130] peer1 data-mesher[222]: time=2026-10-05T15:33:10.628Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer1 # [2707570.643130] peer1 data-mesher[222]: time=2026-10-05T15:33:10.628Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer1 # [2707570.643373] peer1 data-mesher[222]: time=2026-10-05T15:33:10.628Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer1 # [2707570.643506] peer1 data-mesher[222]: time=2026-10-05T15:33:10.629Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer1 # [2707570.817911] peer1 systemd-logind[242]: New seat seat0. container-test-run-dm-wireguard-star> peer1 # [2707570.818159] peer1 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer1 # [2707570.857137] peer1 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer1 # [2707570.871623] peer1 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2707570.871825] peer1 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer1 # [2707571.008140] peer1 systemd-networkd[217]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> controller # [2707570.856946] controller systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> controller # [2707570.871834] controller systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2707570.871963] controller systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer1 # [2707575.653256] peer1 data-mesher[222]: time=2026-10-05T15:33:15.639Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> peer1 # [2707575.653742] peer1 data-mesher[222]: time=2026-10-05T15:33:15.639Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip6/2001:db8:1::1/tcp/7946 container-test-run-dm-wireguard-star> peer1 # [2707575.653935] peer1 data-mesher[222]: time=2026-10-05T15:33:15.639Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip6/2001:db8:1::1/tcp/40418 container-test-run-dm-wireguard-star> controller # [2707575.652993] controller data-mesher[233]: time=2026-10-05T15:33:15.638Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip6/2001:db8:1::2/tcp/7946 container-test-run-dm-wireguard-star> controller # [2707575.654686] controller data-mesher[233]: time=2026-10-05T15:33:15.640Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip4/192.168.1.2/tcp/7946 container-test-run-dm-wireguard-star> controller # [2707575.655356] controller data-mesher[233]: time=2026-10-05T15:33:15.641Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip6/2001:db8:1::2/tcp/7946 container-test-run-dm-wireguard-star> controller: still waiting for container 'controller' to reach ready state... container-test-run-dm-wireguard-star> peer1 # [2707580.643433] peer1 data-mesher[222]: time=2026-10-05T15:33:20.629Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer1 # [2707580.644113] peer1 data-mesher[222]: time=2026-10-05T15:33:20.629Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707580.644665] peer1 data-mesher[222]: time=2026-10-05T15:33:20.630Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707580.644731] peer1 data-mesher[222]: time=2026-10-05T15:33:20.630Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707580.644731] peer1 data-mesher[222]: time=2026-10-05T15:33:20.630Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707580.644731] peer1 data-mesher[222]: time=2026-10-05T15:33:20.630Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707580.644853] peer1 data-mesher[222]: time=2026-10-05T15:33:20.630Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer1 # [2707580.644913] peer1 data-mesher[222]: time=2026-10-05T15:33:20.630Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer1 # [2707580.645034] peer1 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer1 # [2707580.646568] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer1 # [2707580.797544] peer1 data-mesher[222]: time=2026-10-05T15:33:20.782Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 status=204 container-test-run-dm-wireguard-star> peer1 # [2707580.797641] peer1 dm-wg-star-publish[288]: Status: 204 No Content container-test-run-dm-wireguard-star> peer1 # [2707580.797800] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2707580.800876] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2707580.801058] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer1 # [2707580.801600] peer1 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> peer1 # [2707580.888281] peer1 dm-wg-star-reconfig[314]: No controller data available yet, skipping container-test-run-dm-wireguard-star> peer1 # [2707580.889121] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2707580.889229] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2707580.889596] peer1 systemd[1]: Startup finished in 13.384s. container-test-run-dm-wireguard-star> controller # [2707580.643440] controller data-mesher[233]: time=2026-10-05T15:33:20.629Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> controller # [2707580.643440] controller data-mesher[233]: time=2026-10-05T15:33:20.629Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707580.644314] controller data-mesher[233]: time=2026-10-05T15:33:20.630Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707580.644314] controller data-mesher[233]: time=2026-10-05T15:33:20.630Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707580.644974] controller data-mesher[233]: time=2026-10-05T15:33:20.630Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707580.644974] controller data-mesher[233]: time=2026-10-05T15:33:20.630Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707580.645090] controller data-mesher[233]: time=2026-10-05T15:33:20.630Z level=INFO msg="server started" container-test-run-dm-wireguard-star> controller # [2707580.645160] controller data-mesher[233]: time=2026-10-05T15:33:20.630Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> controller # [2707580.645227] controller systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> controller # [2707580.646491] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher... container-test-run-dm-wireguard-star> controller # [2707580.721076] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2707580.728767] controller data-mesher[233]: time=2026-10-05T15:33:20.714Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204 container-test-run-dm-wireguard-star> controller # [2707580.728938] controller dm-wg-star-publish[298]: Status: 204 No Content container-test-run-dm-wireguard-star> controller # [2707580.732961] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher. container-test-run-dm-wireguard-star> controller # [2707580.733761] controller systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> controller # [2707580.813989] controller dm-wg-star-reconfig[325]: No peer data available yet, skipping container-test-run-dm-wireguard-star> controller # [2707580.815077] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2707580.815278] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> controller # [2707580.815681] controller systemd[1]: Startup finished in 13.283s. container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 14.17 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/x4i03lhka9aiwxswmbhjfzzbl77nlxmj-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/x4i03lhka9aiwxswmbhjfzzbl77nlxmj-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 # [2707585.645346] peer1 data-mesher[222]: time=2026-10-05T15:33:25.631Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2707585.645710] peer1 data-mesher[222]: time=2026-10-05T15:33:25.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707585.645833] peer1 data-mesher[222]: time=2026-10-05T15:33:25.631Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707585.645833] peer1 data-mesher[222]: time=2026-10-05T15:33:25.631Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707585.645885] peer1 data-mesher[222]: time=2026-10-05T15:33:25.631Z 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 # [2707585.645979] peer1 data-mesher[222]: time=2026-10-05T15:33:25.631Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2707585.646026] peer1 data-mesher[222]: time=2026-10-05T15:33:25.631Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-05 15:33:20.702 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707585.647771] peer1 data-mesher[222]: time=2026-10-05T15:33:25.633Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707585.647837] peer1 data-mesher[222]: time=2026-10-05T15:33:25.633Z 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 # [2707585.647837] peer1 data-mesher[222]: time=2026-10-05T15:33:25.633Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707585.647879] peer1 data-mesher[222]: time=2026-10-05T15:33:25.633Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2707585.649060] peer1 data-mesher[222]: time=2026-10-05T15:33:25.634Z level=INFO msg="received file request" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> peer1 # [2707585.651385] peer1 data-mesher[222]: time=2026-10-05T15:33:25.637Z level=INFO msg="file transfer complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> peer1 # [2707585.652759] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2707585.786872] peer1 data-mesher[222]: time=2026-10-05T15:33:25.772Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-05 15:33:20.702 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ written=true elapsed=140.832832ms container-test-run-dm-wireguard-star> peer1 # [2707585.800103] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2707585.800604] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> controller # [2707585.645237] controller data-mesher[233]: time=2026-10-05T15:33:25.631Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2707585.645817] controller data-mesher[233]: time=2026-10-05T15:33:25.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707585.646219] controller data-mesher[233]: time=2026-10-05T15:33:25.632Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707585.646273] controller data-mesher[233]: time=2026-10-05T15:33:25.632Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707585.646524] controller data-mesher[233]: time=2026-10-05T15:33:25.632Z level=INFO msg="received file request" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2707585.647184] controller data-mesher[233]: time=2026-10-05T15:33:25.632Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707585.647486] controller data-mesher[233]: time=2026-10-05T15:33:25.633Z 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 # [2707585.647820] controller data-mesher[233]: time=2026-10-05T15:33:25.633Z 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 # [2707585.647886] controller data-mesher[233]: time=2026-10-05T15:33:25.633Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707585.647950] controller data-mesher[233]: time=2026-10-05T15:33:25.633Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> controller # [2707585.647950] controller data-mesher[233]: time=2026-10-05T15:33:25.633Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2707585.648667] controller data-mesher[233]: time=2026-10-05T15:33:25.634Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-05 15:33:20.779 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707585.648927] controller data-mesher[233]: time=2026-10-05T15:33:25.634Z level=INFO msg="file transfer complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2707585.688567] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2707585.788451] controller data-mesher[233]: time=2026-10-05T15:33:25.774Z level=INFO msg="download complete" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-05 15:33:20.779 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf written=true elapsed=140.496707ms container-test-run-dm-wireguard-star> controller # [2707585.800343] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2707585.800531] controller systemd[1]: Finished Reconfigure WireGuard peers 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> peer1: (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::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 787) 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 # [2707587.265584] peer2 systemd-journald[99]: Journal started container-test-run-dm-wireguard-star> peer2 # [2707587.265649] peer2 systemd-journald[99]: Runtime Journal (/run/log/journal/10a6b53aa4934743b663348e53b97b7c) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> peer2 # [2707587.275948] peer2 systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> peer2 # [2707587.289053] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer2 # [2707587.303901] peer2 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer2 # [2707587.304755] peer2 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer2 # [2707587.305434] peer2 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer2 # [2707587.347129] peer2 systemd-journald[99]: Time spent on flushing to /var/log/journal/10a6b53aa4934743b663348e53b97b7c is 1.690ms for 7 entries. container-test-run-dm-wireguard-star> peer2 # [2707587.347129] peer2 systemd-journald[99]: System Journal (/var/log/journal/10a6b53aa4934743b663348e53b97b7c) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer2 # [2707587.360364] peer2 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer2 # [2707587.361035] peer2 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer2 # [2707587.361145] peer2 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer2 # [2707587.362013] peer2 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer2 # [2707587.362065] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [2707587.363157] peer2 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer2 # [2707587.363198] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer2 # [2707587.371850] peer2 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer2 # [2707587.373830] peer2 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer2 # [2707587.388254] peer2 systemd-tmpfiles[183]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2707587.388427] peer2 systemd-tmpfiles[183]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2707587.388542] peer2 systemd-tmpfiles[183]: fchmod() of /var/log/journal/10a6b53aa4934743b663348e53b97b7c failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2707587.388719] peer2 systemd-tmpfiles[183]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2707587.390111] peer2 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer2 # [2707587.391227] peer2 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer2 # [2707587.391985] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer2 # [2707587.406115] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer2 # [2707587.413795] peer2 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer2 # [2707587.415164] peer2 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer2 # [2707587.416952] peer2 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> peer2 # [2707587.417164] peer2 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer2 # [2707587.417434] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer2 # [2707587.418702] peer2 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> peer2 # [2707587.480244] peer2 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer2 # [2707588.139026] peer2 systemd-networkd[215]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2707588.139121] peer2 systemd-networkd[215]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2707588.149530] peer2 systemd-networkd[215]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer2 # [2707588.149700] peer2 systemd-networkd[215]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer2 # [2707588.149847] peer2 systemd-networkd[215]: lo: Link UP container-test-run-dm-wireguard-star> peer2 # [2707588.149852] peer2 systemd-networkd[215]: lo: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2707588.150054] peer2 systemd-networkd[215]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer2 # [2707588.150376] peer2 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer2 # [2707588.151299] peer2 systemd-networkd[215]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer2 # [2707588.151566] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer2 # [2707588.151592] peer2 systemd-networkd[215]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer2 # [2707588.151969] peer2 systemd-networkd[215]: eth1: Link UP container-test-run-dm-wireguard-star> peer2 # [2707588.152177] peer2 systemd-networkd[215]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2707588.184628] peer2 systemd-networkd[215]: wg-star: Link UP container-test-run-dm-wireguard-star> peer2 # [2707588.184632] peer2 systemd-networkd[215]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2707588.210247] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer2 # [2707588.310503] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2707588.311872] peer2 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> peer2 # [2707588.469573] peer2 systemd-resolved[139]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer2 # [2707588.469594] peer2 systemd-resolved[139]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer2 # [2707588.469597] peer2 systemd-resolved[139]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer2 # [2707588.469631] peer2 systemd-resolved[139]: 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 # [2707588.489727] peer2 systemd-resolved[139]: Using system hostname 'peer2'. container-test-run-dm-wireguard-star> peer2 # [2707588.491304] peer2 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> peer2 # [2707588.491433] peer2 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> peer2 # [2707588.491537] peer2 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> peer2 # [2707588.491686] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2707588.491736] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> peer2 # [2707588.491769] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [2707588.491800] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> peer2 # [2707588.491836] peer2 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> peer2 # [2707588.491902] peer2 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> peer2 # [2707588.492138] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> peer2 # [2707588.492336] peer2 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> peer2 # [2707588.492529] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> peer2 # [2707588.492568] peer2 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> peer2 # [2707588.492637] peer2 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> peer2 # [2707588.494580] peer2 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> peer2 # [2707588.495754] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> peer2 # [2707588.497032] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> peer2 # [2707588.498855] peer2 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> peer2 # [2707588.559501] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> peer2 # [2707588.720050] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> peer2 # [2707588.720174] peer2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> peer2 # [2707588.720497] peer2 nsncd[224]: Oct 05 15:33:28.706 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 # [2707588.720293] peer2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer2 # [2707588.722497] peer2 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> peer2 # [2707588.723916] peer2 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> peer2 # [2707588.764711] peer2 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> peer2 # [2707588.766332] peer2 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> peer2 # [2707588.766406] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> peer2 # [2707588.766445] peer2 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> peer2 # [2707588.881746] peer2 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> peer2 # [2707588.882935] peer2 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> peer2 # [2707588.882970] peer2 dbus-broker-launch[225]: Invalid user-name in /nix/store/76bba54qlpzlasgg0vwbcr9xfw1qjrn7-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> peer2 # [2707588.883453] peer2 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> peer2 # [2707588.891196] peer2 dbus-broker-launch[225]: Ready container-test-run-dm-wireguard-star> peer2 # [2707589.315925] peer2 data-mesher[222]: time=2026-10-05T15:33:29.301Z 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 # [2707589.316133] peer2 systemd-networkd[215]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> peer2 # [2707589.317360] peer2 data-mesher[222]: time=2026-10-05T15:33:29.302Z 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 # [2707589.317360] peer2 data-mesher[222]: time=2026-10-05T15:33:29.302Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer2 # [2707589.323447] peer2 data-mesher[222]: time=2026-10-05T15:33:29.309Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer2 # [2707589.323562] peer2 data-mesher[222]: time=2026-10-05T15:33:29.309Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer2 # [2707589.327603] peer2 data-mesher[222]: time=2026-10-05T15:33:29.313Z 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 # [2707589.327650] peer2 data-mesher[222]: time=2026-10-05T15:33:29.313Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer2 # [2707589.327650] peer2 data-mesher[222]: time=2026-10-05T15:33:29.313Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer2 # [2707589.327650] peer2 data-mesher[222]: time=2026-10-05T15:33:29.313Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer2 # [2707589.327650] peer2 data-mesher[222]: time=2026-10-05T15:33:29.313Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer2 # [2707589.327762] peer2 data-mesher[222]: time=2026-10-05T15:33:29.313Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer2 # [2707589.328098] peer2 data-mesher[222]: time=2026-10-05T15:33:29.313Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer2 # [2707589.328542] peer2 data-mesher[222]: time=2026-10-05T15:33:29.314Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer2 # [2707589.333790] peer2 data-mesher[222]: time=2026-10-05T15:33:29.319Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip4/192.168.1.2/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2707589.339814] peer2 data-mesher[222]: time=2026-10-05T15:33:29.325Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2707589.565027] peer2 systemd-logind[242]: New seat seat0. container-test-run-dm-wireguard-star> peer2 # [2707589.565223] peer2 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer2 # [2707589.566967] peer2 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer1 # [2707589.334606] peer1 data-mesher[222]: time=2026-10-05T15:33:29.320Z level=INFO msg="peer connected" peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> controller # [2707589.340662] controller data-mesher[233]: time=2026-10-05T15:33:29.326Z level=INFO msg="peer connected" peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2707589.615473] peer2 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2707589.615717] peer2 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer1 # [2707590.648703] peer1 data-mesher[222]: time=2026-10-05T15:33:30.634Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2707590.649386] peer1 data-mesher[222]: time=2026-10-05T15:33:30.634Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707590.650018] peer1 data-mesher[222]: time=2026-10-05T15:33:30.635Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707590.650018] peer1 data-mesher[222]: time=2026-10-05T15:33:30.635Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707590.650560] peer1 data-mesher[222]: time=2026-10-05T15:33:30.636Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707590.651176] peer1 data-mesher[222]: time=2026-10-05T15:33:30.636Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707590.651249] peer1 data-mesher[222]: time=2026-10-05T15:33:30.637Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2707590.649016] controller data-mesher[233]: time=2026-10-05T15:33:30.634Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2707590.649661] controller data-mesher[233]: time=2026-10-05T15:33:30.634Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707590.649661] controller data-mesher[233]: time=2026-10-05T15:33:30.635Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707590.649661] controller data-mesher[233]: time=2026-10-05T15:33:30.635Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707590.650997] controller data-mesher[233]: time=2026-10-05T15:33:30.636Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707590.651604] controller data-mesher[233]: time=2026-10-05T15:33:30.637Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707590.651668] controller data-mesher[233]: time=2026-10-05T15:33:30.637Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2707595.651717] peer1 data-mesher[222]: time=2026-10-05T15:33:35.637Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2707595.651717] peer1 data-mesher[222]: time=2026-10-05T15:33:35.637Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707595.653335] peer1 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer1 # [2707595.653335] peer1 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707595.653335] peer1 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2707595.654279] peer1 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=INFO msg="received file request" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2707595.654279] peer1 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=INFO msg="received file request" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> peer1 # [2707595.654279] peer1 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=INFO msg="file transfer complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2707595.655561] peer1 data-mesher[222]: time=2026-10-05T15:33:35.641Z level=INFO msg="file transfer complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> controller # [2707595.652435] controller data-mesher[233]: time=2026-10-05T15:33:35.638Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2707595.652435] controller data-mesher[233]: time=2026-10-05T15:33:35.638Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2707595.653972] controller data-mesher[233]: time=2026-10-05T15:33:35.639Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> controller # [2707595.653972] controller data-mesher[233]: time=2026-10-05T15:33:35.639Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2707595.654187] controller data-mesher[233]: time=2026-10-05T15:33:35.639Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer2 # [2707595.652564] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707595.652564] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707595.652985] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z 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> peer2 # [2707595.652985] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z level=DEBUG msg="new file detected" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf 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 # [2707595.653037] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2707595.653072] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2707595.653126] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> peer2 # [2707595.653170] peer2 data-mesher[222]: time=2026-10-05T15:33:35.638Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer2 # [2707595.653199] peer2 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-05 15:33:20.702 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707595.653487] peer2 data-mesher[222]: time=2026-10-05T15:33:35.639Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-05 15:33:20.779 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707595.653650] peer2 data-mesher[222]: time=2026-10-05T15:33:35.639Z 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 # [2707595.653681] peer2 data-mesher[222]: time=2026-10-05T15:33:35.639Z 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 # [2707595.659529] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer2 # [2707595.734249] peer2 data-mesher[222]: time=2026-10-05T15:33:35.720Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-05 15:33:20.702 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf written=true elapsed=81.040203ms container-test-run-dm-wireguard-star> peer2 # [2707595.744910] peer2 data-mesher[222]: time=2026-10-05T15:33:35.730Z level=INFO msg="download complete" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-05 15:33:20.779 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf written=true elapsed=91.493548ms container-test-run-dm-wireguard-star> peer2 # [2707595.799306] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2707595.799559] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state... container-test-run-dm-wireguard-star> peer2 # [2707599.328127] peer2 data-mesher[222]: time=2026-10-05T15:33:39.313Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer2 # [2707599.328127] peer2 data-mesher[222]: time=2026-10-05T15:33:39.313Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> peer2 # [2707599.330084] peer2 data-mesher[222]: time=2026-10-05T15:33:39.315Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707599.330421] peer2 data-mesher[222]: time=2026-10-05T15:33:39.316Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> peer2 # [2707599.330481] peer2 data-mesher[222]: time=2026-10-05T15:33:39.316Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer2 # [2707599.330744] peer2 data-mesher[222]: time=2026-10-05T15:33:39.316Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer2 # [2707599.330751] peer2 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer2 # [2707599.333111] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer2 # [2707599.505602] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2707599.329665] peer1 data-mesher[222]: time=2026-10-05T15:33:39.314Z level=INFO msg="received state sync from peer" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer1 # [2707599.329665] peer1 data-mesher[222]: time=2026-10-05T15:33:39.314Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer2: (finished: waiting for unit data-mesher.service, in 13.68 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/x4i03lhka9aiwxswmbhjfzzbl77nlxmj-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/x4i03lhka9aiwxswmbhjfzzbl77nlxmj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> peer2 # [2707599.656362] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2707599.656659] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2707599.726156] peer2 data-mesher[222]: time=2026-10-05T15:33:39.711Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY status=204 container-test-run-dm-wireguard-star> peer2 # [2707599.726457] peer2 dm-wg-star-publish[300]: Status: 204 No Content container-test-run-dm-wireguard-star> peer2 # [2707599.733325] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2707599.733545] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer2 # [2707599.734114] peer2 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> peer2 # [2707599.734426] peer2 systemd[1]: Startup finished in 12.988s. container-test-run-dm-wireguard-star> peer1 # [2707600.654088] peer1 data-mesher[222]: time=2026-10-05T15:33:40.639Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2707600.654088] peer1 data-mesher[222]: time=2026-10-05T15:33:40.639Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707600.655071] peer1 data-mesher[222]: time=2026-10-05T15:33:40.640Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707600.655071] peer1 data-mesher[222]: time=2026-10-05T15:33:40.640Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2707600.655782] peer1 data-mesher[222]: time=2026-10-05T15:33:40.641Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer1 # [2707600.656162] peer1 data-mesher[222]: time=2026-10-05T15:33:40.641Z 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 # [2707600.656313] peer1 data-mesher[222]: time=2026-10-05T15:33:40.642Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> peer1 # [2707600.656375] peer1 data-mesher[222]: time=2026-10-05T15:33:40.642Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-05 15:33:39.484 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer1 # [2707600.656509] peer1 data-mesher[222]: time=2026-10-05T15:33:40.642Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707600.656583] peer1 data-mesher[222]: time=2026-10-05T15:33:40.642Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2707600.663249] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2707600.751245] peer1 data-mesher[222]: time=2026-10-05T15:33:40.737Z level=INFO msg="download complete" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-05 15:33:39.484 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK written=true elapsed=94.882876ms container-test-run-dm-wireguard-star> peer1 # [2707600.832804] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2707600.833180] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> controller # [2707600.654534] controller data-mesher[233]: time=2026-10-05T15:33:40.640Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2707600.654534] controller data-mesher[233]: time=2026-10-05T15:33:40.640Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707600.656242] controller data-mesher[233]: time=2026-10-05T15:33:40.641Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2707600.656829] controller data-mesher[233]: time=2026-10-05T15:33:40.642Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2707600.656829] controller data-mesher[233]: time=2026-10-05T15:33:40.642Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer2 # [2707600.654795] peer2 data-mesher[222]: time=2026-10-05T15:33:40.640Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707600.654795] peer2 data-mesher[222]: time=2026-10-05T15:33:40.640Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707600.656871] peer2 data-mesher[222]: time=2026-10-05T15:33:40.642Z level=INFO msg="received file request" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> peer2 # [2707600.658358] peer2 data-mesher[222]: time=2026-10-05T15:33:40.644Z level=INFO msg="file transfer complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> peer1 # [2707604.332602] peer1 data-mesher[222]: time=2026-10-05T15:33:44.318Z level=INFO msg="received state sync from peer" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer1 # [2707604.332602] peer1 data-mesher[222]: time=2026-10-05T15:33:44.318Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer2 # [2707604.331766] peer2 data-mesher[222]: time=2026-10-05T15:33:44.317Z level=DEBUG msg="attempting push/pull" peer_count=2 container-test-run-dm-wireguard-star> peer2 # [2707604.331766] peer2 data-mesher[222]: time=2026-10-05T15:33:44.317Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> peer2 # [2707604.334241] peer2 data-mesher[222]: time=2026-10-05T15:33:44.320Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707604.335382] peer2 data-mesher[222]: time=2026-10-05T15:33:44.321Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> peer2 # [2707604.335441] peer2 data-mesher[222]: time=2026-10-05T15:33:44.321Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2707605.656913] peer1 data-mesher[222]: time=2026-10-05T15:33:45.642Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2707605.656913] peer1 data-mesher[222]: time=2026-10-05T15:33:45.642Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707605.659307] peer1 data-mesher[222]: time=2026-10-05T15:33:45.645Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer1 # [2707605.660461] peer1 data-mesher[222]: time=2026-10-05T15:33:45.646Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> peer1 # [2707605.660534] peer1 data-mesher[222]: time=2026-10-05T15:33:45.646Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2707605.657734] controller data-mesher[233]: time=2026-10-05T15:33:45.643Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2707605.657734] controller data-mesher[233]: time=2026-10-05T15:33:45.643Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2707605.658589] controller data-mesher[233]: time=2026-10-05T15:33:45.644Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> controller # [2707605.659141] controller data-mesher[233]: time=2026-10-05T15:33:45.644Z 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 # [2707605.659311] controller data-mesher[233]: time=2026-10-05T15:33:45.645Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> controller # [2707605.659395] controller data-mesher[233]: time=2026-10-05T15:33:45.645Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-05 15:33:39.484 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> controller # [2707605.659715] controller data-mesher[233]: time=2026-10-05T15:33:45.645Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2707605.659785] controller data-mesher[233]: time=2026-10-05T15:33:45.645Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2707605.668241] controller data-mesher[233]: time=2026-10-05T15:33:45.654Z level=INFO msg="download complete" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-05 15:33:39.484 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK written=true elapsed=8.881403ms container-test-run-dm-wireguard-star> controller # [2707605.669749] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2707605.782649] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2707605.782993] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. 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 6.09 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1 container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test $(wg show wg-star peers | wc -l) -gt 1, in 0.01 seconds) container-test-run-dm-wireguard-star> peer2: waiting for success: wg show wg-star peers | grep -q . container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds) container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::28bc:6cf0:1e15:8c64 container-test-run-dm-wireguard-star> peer2 # [2707605.657723] peer2 data-mesher[222]: time=2026-10-05T15:33:45.643Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707605.657723] peer2 data-mesher[222]: time=2026-10-05T15:33:45.643Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer2 # [2707605.658522] peer2 data-mesher[222]: time=2026-10-05T15:33:45.643Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2707605.658522] peer2 data-mesher[222]: time=2026-10-05T15:33:45.643Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2707605.659883] peer2 data-mesher[222]: time=2026-10-05T15:33:45.645Z level=INFO msg="received file request" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> peer2 # [2707605.664780] peer2 data-mesher[222]: time=2026-10-05T15:33:45.650Z level=INFO msg="file transfer complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::28bc:6cf0:1e15:8c64, in 0.24 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 39.37 seconds) container-test-run-dm-wireguard-star> test script finished in 39.40s container-test-run-dm-wireguard-star> cleanup container-test-run-dm-wireguard-star> kill NspawnMachine (pid 50) container-test-run-dm-wireguard-star> kill NspawnMachine (pid 53) container-test-run-dm-wireguard-star> kill NspawnMachine (pid 787) container-test-run-dm-wireguard-star> Container controller terminated by signal KILL. 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.59 seconds)