don't know how to build these paths: /nix/store/pi7hk2mpgjvknsmcgmslfq2c99rhd7ja-container-test-run-dm-wireguard-star.drv warning: ignoring the client-specified setting 'store', because it is a restricted setting and you are not a trusted user building '/nix/store/kka6dnrw3rpqapykc04qawl8f7snf8lz-system-path.drv' on nix-store.geninf.io:50051 system-path> build01: building kka6dnrw3rpqapykc04qawl8f7snf8lz-system-path.drv system-path> structuredAttrs is enabled system-path> created 1724 symlinks in user environment building '/nix/store/asswnqkhy07vhhgdk227vs13crwrqdid-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> build01: building asswnqkhy07vhhgdk227vs13crwrqdid-dbus-1.drv building '/nix/store/321psi5bqglg3zs8f5zy5cg81vgs5lph-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> build01: building 321psi5bqglg3zs8f5zy5cg81vgs5lph-X-Restart-Triggers-dbus-broker.drv X-Restart-Triggers-dbus-broker> structuredAttrs is enabled building '/nix/store/bpdrgxkgy3vqxr5n654isdz3978qykgg-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building bpdrgxkgy3vqxr5n654isdz3978qykgg-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/5k263yvi8zz0av10byq02q92z5nqjp29-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building 5k263yvi8zz0av10byq02q92z5nqjp29-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/nhffr0yys1rbhxprf20z3n39dpyf6lwj-user-units.drv' on nix-store.geninf.io:50051 user-units> build01: building nhffr0yys1rbhxprf20z3n39dpyf6lwj-user-units.drv building '/nix/store/wp5krhx05nizavyabr31wac63fk563rg-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building wp5krhx05nizavyabr31wac63fk563rg-system-units.drv building '/nix/store/7925lb0r635l8wwkyvp3h6j22bgf6qas-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building 7925lb0r635l8wwkyvp3h6j22bgf6qas-system-units.drv building '/nix/store/cbjzscqgfjrsk09qkd32iv2c9b52cdz4-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building cbjzscqgfjrsk09qkd32iv2c9b52cdz4-system-units.drv building '/nix/store/3wld4gpmk89ac4nsg6lnkmafbwy1bdp8-etc.drv' on nix-store.geninf.io:50051 etc> build01: building 3wld4gpmk89ac4nsg6lnkmafbwy1bdp8-etc.drv building '/nix/store/kgy3fhkg92cmnqbpi4h3ngq43jibcg03-etc.drv' on nix-store.geninf.io:50051 etc> build01: building kgy3fhkg92cmnqbpi4h3ngq43jibcg03-etc.drv building '/nix/store/4z3np9j6qjypiw26j4wmxc30xb3rcv3l-etc.drv' on nix-store.geninf.io:50051 etc> build01: building 4z3np9j6qjypiw26j4wmxc30xb3rcv3l-etc.drv building '/nix/store/ypk82z1zhx64xqvmmqk1k68zf878mx60-activate.drv' on nix-store.geninf.io:50051 activate> build01: building ypk82z1zhx64xqvmmqk1k68zf878mx60-activate.drv activate> structuredAttrs is enabled building '/nix/store/vm0af6wr1k9mbnfamgwvpq3m1j7hagwb-activate.drv' on nix-store.geninf.io:50051 activate> build01: building vm0af6wr1k9mbnfamgwvpq3m1j7hagwb-activate.drv activate> structuredAttrs is enabled building '/nix/store/d4hrfgmv26dr8kr2fiadsbbs1w5accwb-activate.drv' on nix-store.geninf.io:50051 activate> build01: building d4hrfgmv26dr8kr2fiadsbbs1w5accwb-activate.drv activate> structuredAttrs is enabled building '/nix/store/zd1ghkcwwf811nsccijhk6nwxsm3vnbh-nixos-system-peer2-test.drv' on nix-store.geninf.io:50051 nixos-system-peer2-test> build01: building zd1ghkcwwf811nsccijhk6nwxsm3vnbh-nixos-system-peer2-test.drv nixos-system-peer2-test> structuredAttrs is enabled building '/nix/store/r9gqnvihlf60jh02xj2ax9ahgq13kkia-nixos-system-peer1-test.drv' on nix-store.geninf.io:50051 nixos-system-peer1-test> build01: building r9gqnvihlf60jh02xj2ax9ahgq13kkia-nixos-system-peer1-test.drv nixos-system-peer1-test> structuredAttrs is enabled building '/nix/store/pzykpzs54jb74yffjd2dilsxmwlsnb2d-nixos-system-controller-test.drv' on nix-store.geninf.io:50051 nixos-system-controller-test> build01: building pzykpzs54jb74yffjd2dilsxmwlsnb2d-nixos-system-controller-test.drv nixos-system-controller-test> structuredAttrs is enabled building '/nix/store/lmv8sknfx5bir4s715xys3hk6kw99bph-run-peer2-nspawn.drv' on nix-store.geninf.io:50051 run-peer2-nspawn> build01: building lmv8sknfx5bir4s715xys3hk6kw99bph-run-peer2-nspawn.drv building '/nix/store/6wb40crh9wzazyygjy8y0dyn71r08nd7-run-peer1-nspawn.drv' on nix-store.geninf.io:50051 run-peer1-nspawn> build01: building 6wb40crh9wzazyygjy8y0dyn71r08nd7-run-peer1-nspawn.drv building '/nix/store/f2y3aswr7y8awfy2c2nblmqmq7awkhrz-run-controller-nspawn.drv' on nix-store.geninf.io:50051 run-controller-nspawn> build01: building f2y3aswr7y8awfy2c2nblmqmq7awkhrz-run-controller-nspawn.drv building '/nix/store/44kl1zgp843092vmj9793m6qc0lawyzx-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> build01: building 44kl1zgp843092vmj9793m6qc0lawyzx-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled building '/nix/store/c43pgqab508s0d99nszxx13s0n32p0pj-nixos-test-driver-dm-wireguard-star.drv' on nix-store.geninf.io:50051 nixos-test-driver-dm-wireguard-star> build01: building c43pgqab508s0d99nszxx13s0n32p0pj-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/pi7hk2mpgjvknsmcgmslfq2c99rhd7ja-container-test-run-dm-wireguard-star.drv' on nix-store.geninf.io:50051 container-test-run-dm-wireguard-star> build01: building pi7hk2mpgjvknsmcgmslfq2c99rhd7ja-container-test-run-dm-wireguard-star.drv container-test-run-dm-wireguard-star> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-dm-wireguard-star> start all VLans container-test-run-dm-wireguard-star> (finished: start all VLans, in 0.00 seconds) container-test-run-dm-wireguard-star> container-test-run-dm-wireguard-star> Test will time out and terminate in 3600.0 seconds container-test-run-dm-wireguard-star> run the VM test script container-test-run-dm-wireguard-star> additionally exposed symbols: container-test-run-dm-wireguard-star> controller, peer1, peer2, container-test-run-dm-wireguard-star> vlan1, container-test-run-dm-wireguard-star> start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh container-test-run-dm-wireguard-star> controller: systemd-nspawn running (pid 50) container-test-run-dm-wireguard-star> controller: Waiting for journal at /build/vm-state-controller/var/log/journal... container-test-run-dm-wireguard-star> peer1: systemd-nspawn running (pid 53) container-test-run-dm-wireguard-star> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal... container-test-run-dm-wireguard-star> controller: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-dm-wireguard-star> nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(controller): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-dm-wireguard-star> ░ Spawning container peer1 on /build/vm-state-peer1. container-test-run-dm-wireguard-star> ░ Spawning container controller on /build/vm-state-controller. container-test-run-dm-wireguard-star> controller # No journal files were found. container-test-run-dm-wireguard-star> controller # No journal boot entry found for the specified boot (+0). container-test-run-dm-wireguard-star> peer1 # No journal files were found. container-test-run-dm-wireguard-star> peer1 # No journal boot entry found for the specified boot (+0). container-test-run-dm-wireguard-star> controller # [2479123.681506] controller systemd-journald[108]: Journal started container-test-run-dm-wireguard-star> controller # [2479123.681564] controller systemd-journald[108]: Runtime Journal (/run/log/journal/dd349bdfd36e408dbe57d80b29a4217a) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> controller # [2479123.685643] controller systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> controller # [2479123.697223] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> controller # [2479123.710438] controller systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> controller # [2479123.748337] controller systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> controller # [2479123.749179] controller systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> controller # [2479123.755461] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/dd349bdfd36e408dbe57d80b29a4217a is 1.729ms for 7 entries. container-test-run-dm-wireguard-star> controller # [2479123.755461] controller systemd-journald[108]: System Journal (/var/log/journal/dd349bdfd36e408dbe57d80b29a4217a) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> controller # [2479123.785369] controller systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> controller # [2479123.785900] controller systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> controller # [2479123.786951] controller systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> controller # [2479123.787048] controller systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> controller # [2479123.787846] controller systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> controller # [2479123.787892] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [2479123.788683] controller systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> controller # [2479123.789315] controller systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> controller # [2479123.789349] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> controller # [2479123.803645] controller systemd-tmpfiles[183]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2479123.803820] controller systemd-tmpfiles[183]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2479123.803943] controller systemd-tmpfiles[183]: fchmod() of /var/log/journal/dd349bdfd36e408dbe57d80b29a4217a failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2479123.804138] controller systemd-tmpfiles[183]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> controller # [2479123.805855] controller systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> controller # [2479123.807155] controller systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> controller # [2479123.808177] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> controller # [2479123.824988] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> controller # [2479123.830013] controller systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> controller # [2479123.831802] controller systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> controller # [2479123.833110] controller systemd[1]: systemd-update-done.service: Failed to spawn executor: No such file or directory container-test-run-dm-wireguard-star> controller # [2479123.833143] controller systemd[1]: systemd-update-done.service: Failed to spawn 'start' task: No such file or directory container-test-run-dm-wireguard-star> controller # [2479123.833206] controller systemd[1]: systemd-update-done.service: Failed with result 'resources'. container-test-run-dm-wireguard-star> controller # [2479123.833294] controller systemd[1]: Failed to start Update is Completed. container-test-run-dm-wireguard-star> controller # [2479123.859332] controller systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> controller # [2479123.859591] controller systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> controller # [2479123.859991] controller systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> controller # [2479123.861585] controller systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> controller # [2479124.500707] controller systemd-networkd[227]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2479124.500796] controller systemd-networkd[227]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> controller # [2479124.510954] controller systemd-networkd[227]: /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 # [2479124.511118] controller systemd-networkd[227]: /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 # [2479124.511269] controller systemd-networkd[227]: lo: Link UP container-test-run-dm-wireguard-star> controller # [2479124.511274] controller systemd-networkd[227]: lo: Gained carrier container-test-run-dm-wireguard-star> controller # [2479124.511478] controller systemd-networkd[227]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> controller # [2479124.511837] controller systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> controller # [2479124.512666] controller systemd-networkd[227]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> controller # [2479124.512998] controller systemd-networkd[227]: wg-star: netdev ready container-test-run-dm-wireguard-star> controller # [2479124.513533] controller systemd-networkd[227]: eth1: Link UP container-test-run-dm-wireguard-star> controller # [2479124.514732] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> controller # [2479124.514850] controller systemd-networkd[227]: eth1: Gained carrier container-test-run-dm-wireguard-star> controller # [2479124.570258] controller systemd-networkd[227]: wg-star: Link UP container-test-run-dm-wireguard-star> controller # [2479124.570262] controller systemd-networkd[227]: wg-star: Gained carrier container-test-run-dm-wireguard-star> controller # [2479124.581946] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer1 # [2479123.671102] peer1 systemd-journald[99]: Journal started container-test-run-dm-wireguard-star> peer1 # [2479123.671162] peer1 systemd-journald[99]: Runtime Journal (/run/log/journal/b0e3c6d39b4749ccb399e50f774b32b8) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> peer1 # [2479123.678287] peer1 systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> peer1 # [2479123.691091] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer1 # [2479123.706297] peer1 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer1 # [2479123.707275] peer1 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer1 # [2479123.707926] peer1 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer1 # [2479123.754830] peer1 systemd-journald[99]: Time spent on flushing to /var/log/journal/b0e3c6d39b4749ccb399e50f774b32b8 is 1.943ms for 7 entries. container-test-run-dm-wireguard-star> peer1 # [2479123.754830] peer1 systemd-journald[99]: System Journal (/var/log/journal/b0e3c6d39b4749ccb399e50f774b32b8) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer1 # [2479123.773334] peer1 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer1 # [2479123.773989] peer1 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer1 # [2479123.775177] peer1 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2479123.775301] peer1 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer1 # [2479123.776314] peer1 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer1 # [2479123.776370] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [2479123.777402] peer1 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer1 # [2479123.778176] peer1 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer1 # [2479123.778214] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer1 # [2479123.794399] peer1 systemd-tmpfiles[174]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2479123.794585] peer1 systemd-tmpfiles[174]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2479123.794712] peer1 systemd-tmpfiles[174]: fchmod() of /var/log/journal/b0e3c6d39b4749ccb399e50f774b32b8 failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2479123.794902] peer1 systemd-tmpfiles[174]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [2479123.796280] peer1 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer1 # [2479123.797524] peer1 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer1 # [2479123.798276] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer1 # [2479123.814665] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer1 # [2479123.820548] peer1 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer1 # [2479123.822106] peer1 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer1 # [2479123.829676] peer1 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> peer1 # [2479123.833087] peer1 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer1 # [2479123.843796] peer1 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> peer1 # [2479123.843937] peer1 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer1 # [2479123.844167] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer1 # [2479123.845301] peer1 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> peer1 # [2479124.506785] 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 # [2479124.506878] 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 # [2479124.523054] 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 # [2479124.523417] 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 # [2479124.523551] peer1 systemd-networkd[217]: lo: Link UP container-test-run-dm-wireguard-star> peer1 # [2479124.523556] peer1 systemd-networkd[217]: lo: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2479124.523754] peer1 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer1 # [2479124.524305] peer1 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer1 # [2479124.572557] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer1 # [2479124.573054] peer1 systemd-networkd[217]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer1 # [2479124.573387] peer1 systemd-networkd[217]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer1 # [2479124.573889] peer1 systemd-networkd[217]: eth1: Link UP container-test-run-dm-wireguard-star> peer1 # [2479124.574117] peer1 systemd-networkd[217]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2479124.628910] peer1 systemd-networkd[217]: wg-star: Link UP container-test-run-dm-wireguard-star> peer1 # [2479124.628915] peer1 systemd-networkd[217]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer1 # [2479124.629981] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> controller # [2479124.690949] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2479124.665949] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2479124.905247] peer1 systemd-resolved[133]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer1 # [2479124.905259] peer1 systemd-resolved[133]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer1 # [2479124.905262] peer1 systemd-resolved[133]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer1 # [2479124.905295] peer1 systemd-resolved[133]: 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 # [2479124.925702] peer1 systemd-resolved[133]: Using system hostname 'peer1'. container-test-run-dm-wireguard-star> peer1 # [2479124.927222] peer1 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> peer1 # [2479124.927351] peer1 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> peer1 # [2479124.927470] peer1 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> peer1 # [2479124.927632] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2479124.927686] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> peer1 # [2479124.927727] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [2479124.927765] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> peer1 # [2479124.927802] peer1 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> peer1 # [2479124.927872] peer1 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> peer1 # [2479124.928122] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> peer1 # [2479124.928330] peer1 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> peer1 # [2479124.928547] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> peer1 # [2479124.928592] peer1 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> peer1 # [2479124.928680] peer1 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> peer1 # [2479124.952831] peer1 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> peer1 # [2479124.954130] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> peer1 # [2479124.955631] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> peer1 # [2479124.957600] peer1 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> peer1 # [2479124.977179] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> peer1 # [2479125.166755] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> peer1 # [2479125.166848] peer1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> peer1 # [2479125.166964] peer1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer1 # [2479125.167167] peer1 nsncd[224]: Oct 03 00:05:45.152 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 # [2479124.878807] controller systemd-resolved[139]: Positive Trust Anchors: container-test-run-dm-wireguard-star> controller # [2479124.878817] controller systemd-resolved[139]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> controller # [2479124.878820] controller systemd-resolved[139]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> controller # [2479124.878855] controller 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> controller # [2479124.899617] controller systemd-resolved[139]: Using system hostname 'controller'. container-test-run-dm-wireguard-star> controller # [2479124.903277] controller systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> controller # [2479124.903361] controller systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> controller # [2479124.903436] controller systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> controller # [2479124.903556] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher. container-test-run-dm-wireguard-star> controller # [2479124.903578] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [2479124.903602] controller systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> controller # [2479124.903621] controller systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> controller # [2479124.903657] controller systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> controller # [2479124.903807] controller systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> controller # [2479124.903937] controller systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> controller # [2479124.904124] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> controller # [2479124.904145] controller systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> controller # [2479124.904196] controller systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> controller # [2479124.906066] controller systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> controller # [2479124.906922] controller systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> controller # [2479124.907927] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> controller # [2479124.909280] controller systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> controller # [2479124.970689] controller systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> controller # [2479125.180309] controller systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> controller # [2479125.180402] controller systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> controller # [2479125.180507] controller systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> controller # [2479125.180740] controller nsncd[234]: Oct 03 00:05:45.166 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 # [2479125.169044] peer1 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> peer1 # [2479125.170470] peer1 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> peer1 # [2479125.220545] peer1 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> peer1 # [2479125.222451] peer1 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> peer1 # [2479125.222541] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> peer1 # [2479125.222581] peer1 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> peer1 # [2479125.322694] peer1 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> peer1 # [2479125.324703] peer1 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> peer1 # [2479125.324703] peer1 dbus-broker-launch[225]: Invalid user-name in /nix/store/6vm4jv8dfnmyv9by9r6gwshp9hz8yq8m-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> peer1 # [2479125.325221] peer1 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> peer1 # [2479125.334257] peer1 dbus-broker-launch[225]: Ready container-test-run-dm-wireguard-star> controller # [2479125.212730] controller systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> controller # [2479125.214113] controller systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> controller # [2479125.226751] controller systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> controller # [2479125.228394] controller systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> controller # [2479125.228470] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> controller # [2479125.228515] controller systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> controller # [2479125.333147] controller dbus-broker-launch[235]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> controller # [2479125.334934] controller dbus-broker-launch[235]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> controller # [2479125.334934] controller dbus-broker-launch[235]: Invalid user-name in /nix/store/6vm4jv8dfnmyv9by9r6gwshp9hz8yq8m-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> controller # [2479125.335372] controller systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> controller # [2479125.344520] controller dbus-broker-launch[235]: Ready container-test-run-dm-wireguard-star> controller # [2479125.568147] controller systemd-networkd[227]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> controller # [2479125.743145] controller data-mesher[232]: time=2026-10-03T00:05:45.728Z 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 # [2479125.744841] controller data-mesher[232]: time=2026-10-03T00:05:45.730Z 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 # [2479125.744841] controller data-mesher[232]: time=2026-10-03T00:05:45.730Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> controller # [2479125.748772] controller data-mesher[232]: time=2026-10-03T00:05:45.734Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> controller # [2479125.748932] controller data-mesher[232]: time=2026-10-03T00:05:45.734Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> controller # [2479125.752948] controller data-mesher[232]: time=2026-10-03T00:05:45.738Z 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 # [2479125.753030] controller data-mesher[232]: time=2026-10-03T00:05:45.738Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> controller # [2479125.753030] controller data-mesher[232]: time=2026-10-03T00:05:45.738Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> controller # [2479125.753030] controller data-mesher[232]: time=2026-10-03T00:05:45.738Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> controller # [2479125.753030] controller data-mesher[232]: time=2026-10-03T00:05:45.738Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> controller # [2479125.753214] controller data-mesher[232]: time=2026-10-03T00:05:45.738Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> controller # [2479125.753214] controller data-mesher[232]: time=2026-10-03T00:05:45.738Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> controller # [2479125.753214] controller data-mesher[232]: time=2026-10-03T00:05:45.739Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> controller # [2479125.761119] controller data-mesher[232]: time=2026-10-03T00:05:45.746Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip4/192.168.1.2/tcp/7946 container-test-run-dm-wireguard-star> peer1 # [2479125.734479] peer1 data-mesher[222]: time=2026-10-03T00:05:45.720Z 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 # [2479125.735647] peer1 data-mesher[222]: time=2026-10-03T00:05:45.721Z 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 # [2479125.735647] peer1 data-mesher[222]: time=2026-10-03T00:05:45.721Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer1 # [2479125.748785] peer1 data-mesher[222]: time=2026-10-03T00:05:45.734Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer1 # [2479125.748920] peer1 data-mesher[222]: time=2026-10-03T00:05:45.734Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer1 # [2479125.754277] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z 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 # [2479125.754336] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer1 # [2479125.754336] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer1 # [2479125.754336] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer1 # [2479125.754336] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer1 # [2479125.754469] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer1 # [2479125.754503] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer1 # [2479125.754534] peer1 data-mesher[222]: time=2026-10-03T00:05:45.740Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer1 # [2479125.760483] peer1 data-mesher[222]: time=2026-10-03T00:05:45.746Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> controller # [2479125.996923] controller systemd-logind[252]: New seat seat0. container-test-run-dm-wireguard-star> controller # [2479125.997116] controller systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> controller # [2479125.999427] controller systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> controller # [2479126.050767] controller systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2479126.050892] controller systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer1 # [2479126.010346] peer1 systemd-logind[242]: New seat seat0. container-test-run-dm-wireguard-star> peer1 # [2479126.010535] peer1 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer1 # [2479126.036675] peer1 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer1 # [2479126.050383] peer1 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2479126.050744] peer1 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer1 # [2479126.176456] peer1 systemd-networkd[217]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> controller: still waiting for container 'controller' to reach ready state... container-test-run-dm-wireguard-star> peer1 # [2479135.754311] peer1 data-mesher[222]: time=2026-10-03T00:05:55.740Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479135.754311] peer1 data-mesher[222]: time=2026-10-03T00:05:55.740Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479135.755089] peer1 data-mesher[222]: time=2026-10-03T00:05:55.740Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer1 # [2479135.755089] peer1 data-mesher[222]: time=2026-10-03T00:05:55.740Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479135.755637] peer1 data-mesher[222]: time=2026-10-03T00:05:55.741Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479135.755637] peer1 data-mesher[222]: time=2026-10-03T00:05:55.741Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479135.755774] peer1 data-mesher[222]: time=2026-10-03T00:05:55.741Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer1 # [2479135.755939] peer1 data-mesher[222]: time=2026-10-03T00:05:55.741Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer1 # [2479135.756349] peer1 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer1 # [2479135.758366] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer1 # [2479135.922193] peer1 data-mesher[222]: time=2026-10-03T00:05:55.907Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 status=204 container-test-run-dm-wireguard-star> peer1 # [2479135.922325] peer1 dm-wg-star-publish[287]: Status: 204 No Content container-test-run-dm-wireguard-star> peer1 # [2479135.925188] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2479135.925874] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2479135.926175] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer1 # [2479135.927856] peer1 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> peer1 # [2479136.035333] peer1 dm-wg-star-reconfig[315]: No controller data available yet, skipping container-test-run-dm-wireguard-star> controller # [2479135.753513] controller data-mesher[232]: time=2026-10-03T00:05:55.739Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> controller # [2479135.754307] controller data-mesher[232]: time=2026-10-03T00:05:55.739Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2479135.754663] controller data-mesher[232]: time=2026-10-03T00:05:55.740Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479135.754663] controller data-mesher[232]: time=2026-10-03T00:05:55.740Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2479135.754791] controller data-mesher[232]: time=2026-10-03T00:05:55.740Z level=INFO msg="server started" container-test-run-dm-wireguard-star> controller # [2479135.754902] controller data-mesher[232]: time=2026-10-03T00:05:55.740Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> controller # [2479135.755121] controller systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> controller # [2479135.755793] controller data-mesher[232]: time=2026-10-03T00:05:55.741Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479135.755793] controller data-mesher[232]: time=2026-10-03T00:05:55.741Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479135.757325] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher... container-test-run-dm-wireguard-star> controller # [2479135.832187] controller data-mesher[232]: time=2026-10-03T00:05:55.817Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204 container-test-run-dm-wireguard-star> controller # [2479135.832471] controller dm-wg-star-publish[295]: Status: 204 No Content container-test-run-dm-wireguard-star> controller # [2479135.834846] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2479135.836347] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher. container-test-run-dm-wireguard-star> controller # [2479135.837155] controller systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> controller # [2479135.930297] controller dm-wg-star-reconfig[323]: No peer data available yet, skipping container-test-run-dm-wireguard-star> controller # [2479135.931525] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2479135.956578] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> controller # [2479135.957173] controller systemd[1]: Startup finished in 12.732s. container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 13.68 seconds) container-test-run-dm-wireguard-star> peer1: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> peer1: (finished: waiting for unit data-mesher.service, in 0.02 seconds) container-test-run-dm-wireguard-star> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-dm-wireguard-star> File "/nix/store/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-dm-wireguard-star> File "/nix/store/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 0.01 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2479136.036139] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2479136.036262] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2479136.036879] peer1 systemd[1]: Startup finished in 12.844s. container-test-run-dm-wireguard-star> controller # [2479140.757132] controller data-mesher[232]: time=2026-10-03T00:06:00.742Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2479140.757896] controller data-mesher[232]: time=2026-10-03T00:06:00.742Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2479140.758683] controller data-mesher[232]: time=2026-10-03T00:06:00.744Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479140.758779] controller data-mesher[232]: time=2026-10-03T00:06:00.744Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479140.758852] controller data-mesher[232]: time=2026-10-03T00:06:00.744Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479140.758903] controller data-mesher[232]: time=2026-10-03T00:06:00.744Z level=INFO msg="received file request" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2479140.759291] controller data-mesher[232]: time=2026-10-03T00:06:00.745Z 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 # [2479140.759291] controller data-mesher[232]: time=2026-10-03T00:06:00.745Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2479140.759410] controller data-mesher[232]: time=2026-10-03T00:06:00.745Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2479140.759410] controller data-mesher[232]: time=2026-10-03T00:06:00.745Z 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 # [2479140.759501] controller data-mesher[232]: time=2026-10-03T00:06:00.745Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> controller # [2479140.759501] controller data-mesher[232]: time=2026-10-03T00:06:00.745Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-03 00:05:55.904 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479140.760565] controller data-mesher[232]: time=2026-10-03T00:06:00.746Z level=INFO msg="file transfer complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2479140.765838] controller data-mesher[232]: time=2026-10-03T00:06:00.751Z level=INFO msg="download complete" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-03 00:05:55.904 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf written=true elapsed=6.336846ms container-test-run-dm-wireguard-star> controller # [2479140.768551] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2479140.914298] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2479140.914676] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2479140.757593] peer1 data-mesher[222]: time=2026-10-03T00:06:00.743Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2479140.757593] peer1 data-mesher[222]: time=2026-10-03T00:06:00.743Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479140.758442] peer1 data-mesher[222]: time=2026-10-03T00:06:00.743Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479140.758442] peer1 data-mesher[222]: time=2026-10-03T00:06:00.743Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479140.758442] peer1 data-mesher[222]: time=2026-10-03T00:06:00.743Z 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 # [2479140.758442] peer1 data-mesher[222]: time=2026-10-03T00:06:00.743Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [2479140.758442] peer1 data-mesher[222]: time=2026-10-03T00:06:00.744Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-03 00:05:55.814 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479140.759915] peer1 data-mesher[222]: time=2026-10-03T00:06:00.745Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479140.760050] peer1 data-mesher[222]: time=2026-10-03T00:06:00.745Z 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 # [2479140.760050] peer1 data-mesher[222]: time=2026-10-03T00:06:00.745Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479140.760050] peer1 data-mesher[222]: time=2026-10-03T00:06:00.745Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [2479140.761013] peer1 data-mesher[222]: time=2026-10-03T00:06:00.746Z level=INFO msg="received file request" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> peer1 # [2479140.763679] peer1 data-mesher[222]: time=2026-10-03T00:06:00.749Z level=INFO msg="file transfer complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> peer1 # [2479140.765571] peer1 data-mesher[222]: time=2026-10-03T00:06:00.751Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-03 00:05:55.814 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ written=true elapsed=7.36166ms container-test-run-dm-wireguard-star> peer1 # [2479140.768203] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [2479140.915202] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [2479140.915356] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/dm_wg_star_wg_star/controller, in 5.05 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q . container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | grep -q ., in 0.01 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: wg show wg-star peers | grep -q . container-test-run-dm-wireguard-star> controller: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: wg show wg-star peers | grep -q . container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: wg show wg-star peers | grep -q ., in 0.01 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::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 785) container-test-run-dm-wireguard-star> peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal... container-test-run-dm-wireguard-star> peer2: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(peer2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-dm-wireguard-star> ░ Spawning container peer2 on /build/vm-state-peer2. container-test-run-dm-wireguard-star> peer2 # No journal files were found. container-test-run-dm-wireguard-star> peer2 # No journal boot entry found for the specified boot (+0). container-test-run-dm-wireguard-star> peer2 # [2479142.490212] peer2 systemd-journald[99]: Journal started container-test-run-dm-wireguard-star> peer2 # [2479142.490271] peer2 systemd-journald[99]: Runtime Journal (/run/log/journal/85a1987f679c4396acadd30b3214fef6) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> peer2 # [2479142.494537] peer2 systemd[1]: Finished Apply Kernel Variables. container-test-run-dm-wireguard-star> peer2 # [2479142.506004] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer2 # [2479142.518860] peer2 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer2 # [2479142.519725] peer2 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer2 # [2479142.520434] peer2 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer2 # [2479142.530734] peer2 systemd-journald[99]: Time spent on flushing to /var/log/journal/85a1987f679c4396acadd30b3214fef6 is 2.352ms for 7 entries. container-test-run-dm-wireguard-star> peer2 # [2479142.530734] peer2 systemd-journald[99]: System Journal (/var/log/journal/85a1987f679c4396acadd30b3214fef6) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer2 # [2479142.537768] peer2 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer2 # [2479142.538336] peer2 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer2 # [2479142.538446] peer2 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer2 # [2479142.539314] peer2 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer2 # [2479142.539366] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [2479142.540493] peer2 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer2 # [2479142.540533] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer2 # [2479142.541042] peer2 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer2 # [2479142.542555] peer2 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer2 # [2479142.560410] peer2 systemd-tmpfiles[148]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2479142.560619] peer2 systemd-tmpfiles[148]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2479142.560762] peer2 systemd-tmpfiles[148]: fchmod() of /var/log/journal/85a1987f679c4396acadd30b3214fef6 failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2479142.560972] peer2 systemd-tmpfiles[148]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2479142.562355] peer2 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer2 # [2479142.563457] peer2 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer2 # [2479142.564495] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer2 # [2479142.580705] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer2 # [2479142.582329] peer2 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> peer2 # [2479142.587210] peer2 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer2 # [2479142.588528] peer2 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer2 # [2479142.599999] peer2 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer2 # [2479142.670413] peer2 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> peer2 # [2479142.670578] peer2 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer2 # [2479142.670795] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer2 # [2479142.671892] peer2 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> peer2 # [2479143.363520] peer2 systemd-networkd[217]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2479143.363607] peer2 systemd-networkd[217]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [2479143.373263] peer2 systemd-networkd[217]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer2 # [2479143.373426] peer2 systemd-networkd[217]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer2 # [2479143.373568] peer2 systemd-networkd[217]: lo: Link UP container-test-run-dm-wireguard-star> peer2 # [2479143.373573] peer2 systemd-networkd[217]: lo: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2479143.373748] peer2 systemd-networkd[217]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer2 # [2479143.374160] peer2 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer2 # [2479143.374657] peer2 systemd-networkd[217]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer2 # [2479143.374957] peer2 systemd-networkd[217]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer2 # [2479143.375520] peer2 systemd-networkd[217]: eth1: Link UP container-test-run-dm-wireguard-star> peer2 # [2479143.375930] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer2 # [2479143.375932] peer2 systemd-networkd[217]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2479143.404462] peer2 systemd-networkd[217]: wg-star: Link UP container-test-run-dm-wireguard-star> peer2 # [2479143.404467] peer2 systemd-networkd[217]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer2 # [2479143.429226] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer2 # [2479143.500564] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2479143.564869] peer2 systemd-resolved[130]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer2 # [2479143.564879] peer2 systemd-resolved[130]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer2 # [2479143.564882] peer2 systemd-resolved[130]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer2 # [2479143.564919] peer2 systemd-resolved[130]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test container-test-run-dm-wireguard-star> peer2 # [2479143.584978] peer2 systemd-resolved[130]: Using system hostname 'peer2'. container-test-run-dm-wireguard-star> peer2 # [2479143.586506] peer2 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> peer2 # [2479143.586636] peer2 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> peer2 # [2479143.586746] peer2 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> peer2 # [2479143.586918] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2479143.586972] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> peer2 # [2479143.587010] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [2479143.587044] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> peer2 # [2479143.587082] peer2 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> peer2 # [2479143.587151] peer2 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> peer2 # [2479143.587370] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> peer2 # [2479143.587574] peer2 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> peer2 # [2479143.587791] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> peer2 # [2479143.587833] peer2 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> peer2 # [2479143.587910] peer2 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> peer2 # [2479143.590108] peer2 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> peer2 # [2479143.591456] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> peer2 # [2479143.592889] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> peer2 # [2479143.594951] peer2 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> peer2 # [2479143.646697] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> peer2 # [2479143.806959] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> peer2 # [2479143.807072] peer2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> peer2 # [2479143.807176] peer2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer2 # [2479143.807527] peer2 nsncd[224]: Oct 03 00:06:03.793 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 # [2479143.809329] peer2 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> peer2 # [2479143.810826] peer2 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> peer2 # [2479143.860419] peer2 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> peer2 # [2479143.862024] peer2 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> peer2 # [2479143.862103] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> peer2 # [2479143.862143] peer2 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> peer2 # [2479143.943243] peer2 dbus-broker-launch[225]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> peer2 # [2479143.944443] peer2 dbus-broker-launch[225]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> peer2 # [2479143.944443] peer2 dbus-broker-launch[225]: Invalid user-name in /nix/store/6vm4jv8dfnmyv9by9r6gwshp9hz8yq8m-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> peer2 # [2479143.945147] peer2 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> peer2 # [2479143.953763] peer2 dbus-broker-launch[225]: Ready container-test-run-dm-wireguard-star> peer2 # [2479144.360995] peer2 data-mesher[222]: time=2026-10-03T00:06:04.346Z 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 # [2479144.362100] peer2 data-mesher[222]: time=2026-10-03T00:06:04.347Z 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 # [2479144.362100] peer2 data-mesher[222]: time=2026-10-03T00:06:04.347Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer2 # [2479144.363998] peer2 data-mesher[222]: time=2026-10-03T00:06:04.349Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer2 # [2479144.364142] peer2 data-mesher[222]: time=2026-10-03T00:06:04.349Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer2 # [2479144.367930] peer2 data-mesher[222]: time=2026-10-03T00:06:04.353Z 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 # [2479144.368012] peer2 data-mesher[222]: time=2026-10-03T00:06:04.353Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer2 # [2479144.368012] peer2 data-mesher[222]: time=2026-10-03T00:06:04.353Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer2 # [2479144.368012] peer2 data-mesher[222]: time=2026-10-03T00:06:04.353Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer2 # [2479144.368012] peer2 data-mesher[222]: time=2026-10-03T00:06:04.353Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer2 # [2479144.368203] peer2 data-mesher[222]: time=2026-10-03T00:06:04.353Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer2 # [2479144.368203] peer2 data-mesher[222]: time=2026-10-03T00:06:04.353Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer2 # [2479144.368203] peer2 data-mesher[222]: time=2026-10-03T00:06:04.354Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer2 # [2479144.374958] peer2 data-mesher[222]: time=2026-10-03T00:06:04.360Z level=INFO msg="peer connected" peer_id=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2479144.381083] peer2 data-mesher[222]: time=2026-10-03T00:06:04.366Z level=INFO msg="peer connected" peer_id=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf remote_addr=/ip4/192.168.1.2/tcp/7946 container-test-run-dm-wireguard-star> peer1 # [2479144.382198] peer1 data-mesher[222]: time=2026-10-03T00:06:04.367Z level=INFO msg="peer connected" peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> controller # [2479144.376799] controller data-mesher[232]: time=2026-10-03T00:06:04.362Z level=INFO msg="peer connected" peer_id=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [2479144.617285] peer2 systemd-logind[242]: New seat seat0. container-test-run-dm-wireguard-star> peer2 # [2479144.617493] peer2 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer2 # [2479144.619550] peer2 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer2 # [2479144.671094] peer2 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2479144.671404] peer2 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer2 # [2479144.736119] peer2 systemd-networkd[217]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> controller # [2479145.759699] controller data-mesher[232]: time=2026-10-03T00:06:05.745Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2479145.759699] controller data-mesher[232]: time=2026-10-03T00:06:05.745Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2479145.761457] controller data-mesher[232]: time=2026-10-03T00:06:05.747Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> controller # [2479145.761457] controller data-mesher[232]: time=2026-10-03T00:06:05.747Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2479145.761585] controller data-mesher[232]: time=2026-10-03T00:06:05.747Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2479145.761720] controller data-mesher[232]: time=2026-10-03T00:06:05.747Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479145.761796] controller data-mesher[232]: time=2026-10-03T00:06:05.747Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479145.761939] controller data-mesher[232]: time=2026-10-03T00:06:05.747Z level=INFO msg="received file request" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [2479145.762005] controller data-mesher[232]: time=2026-10-03T00:06:05.747Z level=INFO msg="received file request" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> controller # [2479145.763143] controller data-mesher[232]: time=2026-10-03T00:06:05.748Z level=INFO msg="file transfer complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> controller # [2479145.764524] controller data-mesher[232]: time=2026-10-03T00:06:05.750Z level=INFO msg="file transfer complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer2 # [2479145.760559] peer2 data-mesher[222]: time=2026-10-03T00:06:05.746Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2479145.760559] peer2 data-mesher[222]: time=2026-10-03T00:06:05.746Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2479145.761267] peer2 data-mesher[222]: time=2026-10-03T00:06:05.746Z 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 # [2479145.761267] peer2 data-mesher[222]: time=2026-10-03T00:06:05.747Z 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 # [2479145.761377] peer2 data-mesher[222]: time=2026-10-03T00:06:05.747Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 container-test-run-dm-wireguard-star> peer2 # [2479145.761377] peer2 data-mesher[222]: time=2026-10-03T00:06:05.747Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer2 # [2479145.761477] peer2 data-mesher[222]: time=2026-10-03T00:06:05.747Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-03 00:05:55.814 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2479145.761477] peer2 data-mesher[222]: time=2026-10-03T00:06:05.747Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-03 00:05:55.904 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2479145.767087] peer2 data-mesher[222]: time=2026-10-03T00:06:05.752Z level=INFO msg="download complete" name=dm_wg_star_wg_star/bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4 signed_at="2026-10-03 00:05:55.904 +0000 UTC" signed_by="bB9Kv45NKM8kzJX19k4dB2Sei0DX65f58ZDrXTwIeN4=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ written=true elapsed=5.696118ms container-test-run-dm-wireguard-star> peer2 # [2479145.768126] peer2 data-mesher[222]: time=2026-10-03T00:06:05.753Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-03 00:05:55.814 +0000 UTC" signed_by="DdAWC6KVow4OB6mpjNG1hnNj1L2IafSvLkKLWsnuRPk=" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ written=true elapsed=6.747532ms container-test-run-dm-wireguard-star> peer2 # [2479145.769747] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer2 # [2479145.924254] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2479145.924567] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer1 # [2479145.760811] peer1 data-mesher[222]: time=2026-10-03T00:06:05.746Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2479145.761394] peer1 data-mesher[222]: time=2026-10-03T00:06:05.746Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479145.764802] peer1 data-mesher[222]: time=2026-10-03T00:06:05.750Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479145.765432] peer1 data-mesher[222]: time=2026-10-03T00:06:05.751Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479145.765502] peer1 data-mesher[222]: time=2026-10-03T00:06:05.751Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2479150.761930] controller data-mesher[232]: time=2026-10-03T00:06:10.747Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2479150.762607] controller data-mesher[232]: time=2026-10-03T00:06:10.747Z level=DEBUG msg="initiating state exchange" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2479150.763649] controller data-mesher[232]: time=2026-10-03T00:06:10.749Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479150.764290] controller data-mesher[232]: time=2026-10-03T00:06:10.750Z level=INFO msg="state exchange complete" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf timeout=5s container-test-run-dm-wireguard-star> controller # [2479150.764429] controller data-mesher[232]: time=2026-10-03T00:06:10.750Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2479150.767018] controller data-mesher[232]: time=2026-10-03T00:06:10.752Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479150.767018] controller data-mesher[232]: time=2026-10-03T00:06:10.752Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> peer1 # [2479150.762642] peer1 data-mesher[222]: time=2026-10-03T00:06:10.748Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479150.762642] peer1 data-mesher[222]: time=2026-10-03T00:06:10.748Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479150.765610] peer1 data-mesher[222]: time=2026-10-03T00:06:10.751Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2479150.765704] peer1 data-mesher[222]: time=2026-10-03T00:06:10.751Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479150.767199] peer1 data-mesher[222]: time=2026-10-03T00:06:10.752Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479150.767786] peer1 data-mesher[222]: time=2026-10-03T00:06:10.753Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479150.767865] peer1 data-mesher[222]: time=2026-10-03T00:06:10.753Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state... container-test-run-dm-wireguard-star> peer2 # [2479154.369276] peer2 data-mesher[222]: time=2026-10-03T00:06:14.355Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer2 # [2479154.369276] peer2 data-mesher[222]: time=2026-10-03T00:06:14.355Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer2 # [2479154.371063] peer2 data-mesher[222]: time=2026-10-03T00:06:14.356Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2479154.371674] peer2 data-mesher[222]: time=2026-10-03T00:06:14.357Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer2 # [2479154.371747] peer2 data-mesher[222]: time=2026-10-03T00:06:14.357Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer2 # [2479154.371909] peer2 data-mesher[222]: time=2026-10-03T00:06:14.357Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer2 # [2479154.372252] peer2 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer2 # [2479154.374506] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer2 # [2479154.537449] peer2 data-mesher[222]: time=2026-10-03T00:06:14.523Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY status=204 container-test-run-dm-wireguard-star> peer2 # [2479154.537614] peer2 dm-wg-star-publish[298]: Status: 204 No Content container-test-run-dm-wireguard-star> peer2 # [2479154.539885] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer2 # [2479154.541723] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2479154.542048] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer2 # [2479154.543078] peer2 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> controller # [2479154.370851] controller data-mesher[232]: time=2026-10-03T00:06:14.355Z level=INFO msg="received state sync from peer" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> controller # [2479154.370851] controller data-mesher[232]: time=2026-10-03T00:06:14.355Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> peer2 # [2479154.686876] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [2479154.687191] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer2 # [2479154.687842] peer2 systemd[1]: Startup finished in 12.654s. 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/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller: waiting for success: test $(ls /var/lib/data-mesher/files/home/dm_wg_star_wg_star/ | grep -v controller | wc -l) -gt 1 container-test-run-dm-wireguard-star> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead. container-test-run-dm-wireguard-star> File "/nix/store/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller # [2479155.765366] controller data-mesher[232]: time=2026-10-03T00:06:15.751Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [2479155.765366] controller data-mesher[232]: time=2026-10-03T00:06:15.751Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2479155.767269] controller data-mesher[232]: time=2026-10-03T00:06:15.753Z level=INFO msg="merging remote state" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> controller # [2479155.767867] controller data-mesher[232]: time=2026-10-03T00:06:15.753Z 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 # [2479155.768322] controller data-mesher[232]: time=2026-10-03T00:06:15.754Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> controller # [2479155.768417] controller data-mesher[232]: time=2026-10-03T00:06:15.754Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-03 00:06:14.519 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK container-test-run-dm-wireguard-star> controller # [2479155.768492] controller data-mesher[232]: time=2026-10-03T00:06:15.754Z level=INFO msg="state exchange complete" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK timeout=5s container-test-run-dm-wireguard-star> controller # [2479155.768563] controller data-mesher[232]: time=2026-10-03T00:06:15.754Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [2479155.770448] controller data-mesher[232]: time=2026-10-03T00:06:15.755Z level=INFO msg="received state sync from peer" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479155.770448] controller data-mesher[232]: time=2026-10-03T00:06:15.755Z level=INFO msg="merging remote state" peer=12D3KooWH6Rshcg8miQasumyMdtMBgzGDx8m2QpCazPZhrmcSCsf container-test-run-dm-wireguard-star> controller # [2479155.774613] controller data-mesher[232]: time=2026-10-03T00:06:15.760Z level=INFO msg="download complete" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY signed_at="2026-10-03 00:06:14.519 +0000 UTC" signed_by="+M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY=" peer=12D3KooWSZaduYTHJzakXPtkUZWcK4pmosp237jD7JnWjwG9JwjK written=true elapsed=6.233805ms container-test-run-dm-wireguard-star> controller # [2479155.777816] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [2479155.943396] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [2479155.943747] 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 1.03 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: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::28bc:6cf0:1e15:8c64, in 0.01 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::c289:fe9d:a6bc:e45b container-test-run-dm-wireguard-star> peer2 # [2479155.766162] peer2 data-mesher[222]: time=2026-10-03T00:06:15.751Z level=INFO msg="received state sync from peer" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2479155.766162] peer2 data-mesher[222]: time=2026-10-03T00:06:15.751Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer2 # [2479155.769048] peer2 data-mesher[222]: time=2026-10-03T00:06:15.754Z level=INFO msg="received file request" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY container-test-run-dm-wireguard-star> peer2 # [2479155.772256] peer2 data-mesher[222]: time=2026-10-03T00:06:15.758Z level=INFO msg="file transfer complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ network="sFUEhrCVYv6MEvtAOK9EN1Nb4h7HkDcQN56oesCaO70=" name=dm_wg_star_wg_star/-M0QRtC0j82sjlNSgJeZap4jYFL3GKXvbfMdl7nwBjY 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 33.61 seconds) container-test-run-dm-wireguard-star> peer1 # [2479155.769047] peer1 data-mesher[222]: time=2026-10-03T00:06:15.754Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [2479155.769047] peer1 data-mesher[222]: time=2026-10-03T00:06:15.754Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479155.770461] peer1 data-mesher[222]: time=2026-10-03T00:06:15.756Z level=INFO msg="merging remote state" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ container-test-run-dm-wireguard-star> peer1 # [2479155.770907] peer1 data-mesher[222]: time=2026-10-03T00:06:15.756Z level=INFO msg="state exchange complete" peer=12D3KooWRb7fY96LruvscwjABTE3L1vsgsVSwV8iKZTbMBsn4WHZ timeout=5s container-test-run-dm-wireguard-star> peer1 # [2479155.770983] peer1 data-mesher[222]: time=2026-10-03T00:06:15.756Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> test script finished in 33.65s container-test-run-dm-wireguard-star> cleanup container-test-run-dm-wireguard-star> kill NspawnMachine (pid 50) container-test-run-dm-wireguard-star> kill NspawnMachine (pid 53) container-test-run-dm-wireguard-star> Container controller terminated by signal KILL. container-test-run-dm-wireguard-star> kill NspawnMachine (pid 785) container-test-run-dm-wireguard-star> Container peer1 terminated by signal KILL. container-test-run-dm-wireguard-star> Container peer2 terminated by signal KILL. container-test-run-dm-wireguard-star> (finished: cleanup, in 0.59 seconds)