don't know how to build these paths: /nix/store/nbjw4cx2i6xbr8sm9hhn4djacczgqf8g-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/hqp8xai27chclyn7bd9lb1csm6z4lvy3-unit-40-wg-star.network.drv' on nix-store.geninf.io:50051 unit-40-wg-star.network> build01: building hqp8xai27chclyn7bd9lb1csm6z4lvy3-unit-40-wg-star.network.drv building '/nix/store/5ssr195kndpix40bf2pzil04qi0lda73-unit-40-wg-star.network.drv' on nix-store.geninf.io:50051 unit-40-wg-star.network> build01: building 5ssr195kndpix40bf2pzil04qi0lda73-unit-40-wg-star.network.drv building '/nix/store/1v7lajycn9pn7agwmrl26pfrds8zbs3x-unit-40-wg-star.network.drv' on nix-store.geninf.io:50051 unit-40-wg-star.network> build01: building 1v7lajycn9pn7agwmrl26pfrds8zbs3x-unit-40-wg-star.network.drv building '/nix/store/hvi0cw4zak0ljg0y091phx5ikf1rg5jn-unit-script-dm-wg-star-wg-star-reconfig-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-reconfig-start> build01: building hvi0cw4zak0ljg0y091phx5ikf1rg5jn-unit-script-dm-wg-star-wg-star-reconfig-start.drv building '/nix/store/00lbxcgq442dzf7kqcg668v7ylkj7bmz-unit-dm-wg-star-wg-star-reconfig.path.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.path> build01: building 00lbxcgq442dzf7kqcg668v7ylkj7bmz-unit-dm-wg-star-wg-star-reconfig.path.drv building '/nix/store/jr3rprkfzy4zz9rh30g5pkncgpdvxh52-firewall-start.drv' on nix-store.geninf.io:50051 firewall-start> build01: building jr3rprkfzy4zz9rh30g5pkncgpdvxh52-firewall-start.drv building '/nix/store/sina5izf8naj2lmnyjdkibqxf0ii2sjh-vars-check-dm-wireguard-star.drv' on nix-store.geninf.io:50051 vars-check-dm-wireguard-star> build01: building sina5izf8naj2lmnyjdkibqxf0ii2sjh-vars-check-dm-wireguard-star.drv building '/nix/store/pbgk9rizx8as0ad1h1kwalpq6dyw1lmd-dm.toml.drv' on nix-store.geninf.io:50051 dm.toml> build01: building pbgk9rizx8as0ad1h1kwalpq6dyw1lmd-dm.toml.drv building '/nix/store/rba7pj6kigk2hkc32zm7xzc8ybpz5zcm-dm.toml.drv' on nix-store.geninf.io:50051 dm.toml> build01: building rba7pj6kigk2hkc32zm7xzc8ybpz5zcm-dm.toml.drv building '/nix/store/dynl8qxzv872xlzsp4yqjmnmrb4v9m4r-unit-script-dm-wg-star-wg-star-publish-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-publish-start> build01: building dynl8qxzv872xlzsp4yqjmnmrb4v9m4r-unit-script-dm-wg-star-wg-star-publish-start.drv building '/nix/store/ywivrnj5yy2jdrlxy1fbmh9n6hd51b28-unit-40-wg-star.netdev.drv' on nix-store.geninf.io:50051 unit-40-wg-star.netdev> build01: building ywivrnj5yy2jdrlxy1fbmh9n6hd51b28-unit-40-wg-star.netdev.drv building '/nix/store/sxbpr0r96358d1sc02ydxawfj1fqc7qg-unit-script-dm-wg-star-wg-star-reconfig-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-reconfig-start> build01: building sxbpr0r96358d1sc02ydxawfj1fqc7qg-unit-script-dm-wg-star-wg-star-reconfig-start.drv building '/nix/store/f2grvyi1r1krrpnn2rcfdzkxmk863pj1-unit-script-dm-wg-star-wg-star-publish-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-publish-start> build01: building f2grvyi1r1krrpnn2rcfdzkxmk863pj1-unit-script-dm-wg-star-wg-star-publish-start.drv building '/nix/store/shhp0g08pfgjnl37zv7q5v4154ha8rag-extra-hosts.drv' on nix-store.geninf.io:50051 extra-hosts> build01: building shhp0g08pfgjnl37zv7q5v4154ha8rag-extra-hosts.drv building '/nix/store/q71qdjy4x9d7zn1d0107gwcc4l4gyxh4-unit-dm-wg-star-wg-star-reconfig.path.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.path> build01: building q71qdjy4x9d7zn1d0107gwcc4l4gyxh4-unit-dm-wg-star-wg-star-reconfig.path.drv building '/nix/store/rgjziwq8j6s46n6ziqjsl4whn14s2qw1-firewall-start.drv' on nix-store.geninf.io:50051 firewall-start> build01: building rgjziwq8j6s46n6ziqjsl4whn14s2qw1-firewall-start.drv building '/nix/store/j2bvyb1b91macddsji6wlwfhdlxzjyj5-unit-script-dm-wg-star-wg-star-publish-start.drv' on nix-store.geninf.io:50051 unit-script-dm-wg-star-wg-star-publish-start> build01: building j2bvyb1b91macddsji6wlwfhdlxzjyj5-unit-script-dm-wg-star-wg-star-publish-start.drv building '/nix/store/fwi7jzz4ygp48imjlx7kdydxphrm8hjz-unit-dm-wg-star-wg-star-publish.timer.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.timer> build01: building fwi7jzz4ygp48imjlx7kdydxphrm8hjz-unit-dm-wg-star-wg-star-publish.timer.drv building '/nix/store/7wc511ccglrb9l2qyl29v9179djyb4pc-mount-secret-fs.drv' on nix-store.geninf.io:50051 mount-secret-fs> build01: building 7wc511ccglrb9l2qyl29v9179djyb4pc-mount-secret-fs.drv building '/nix/store/g8i5y1xr3cqhar43j4pf1pnk2d2plmkp-unit-99-ethernet-default-dhcp.network.drv' on nix-store.geninf.io:50051 unit-99-ethernet-default-dhcp.network> build01: building g8i5y1xr3cqhar43j4pf1pnk2d2plmkp-unit-99-ethernet-default-dhcp.network.drv building '/nix/store/ddmkkf6nzsdhny5w718lmqnklqf9f6zv-string-hosts.drv' on nix-store.geninf.io:50051 string-hosts> build01: building ddmkkf6nzsdhny5w718lmqnklqf9f6zv-string-hosts.drv building '/nix/store/7m7cymy4l617yid6xl86iv55dq5mx06g-string-hosts.drv' on nix-store.geninf.io:50051 string-hosts> build01: building 7m7cymy4l617yid6xl86iv55dq5mx06g-string-hosts.drv building '/nix/store/fzanj3nilcyak706zvcchdbd6hldlpwq-dm.toml.drv' on nix-store.geninf.io:50051 dm.toml> build01: building fzanj3nilcyak706zvcchdbd6hldlpwq-dm.toml.drv building '/nix/store/ax3bm1q8c0925w6997z51yzynnlmsdmj-string-hosts.drv' on nix-store.geninf.io:50051 string-hosts> build01: building ax3bm1q8c0925w6997z51yzynnlmsdmj-string-hosts.drv building '/nix/store/5g1g3vkfs4k47yawvp9q3pf8ckyjwkaz-unit-99-wireless-client-dhcp.network.drv' on nix-store.geninf.io:50051 unit-99-wireless-client-dhcp.network> build01: building 5g1g3vkfs4k47yawvp9q3pf8ckyjwkaz-unit-99-wireless-client-dhcp.network.drv building '/nix/store/ghlvx787yibp6a1d9w6j3wh9hahqxbwg-test-script.drv' on nix-store.geninf.io:50051 test-script> build01: building ghlvx787yibp6a1d9w6j3wh9hahqxbwg-test-script.drv unit-40-wg-star.network> structuredAttrs is enabled building '/nix/store/w55v5w3jw0pv8p531rv27qllla0jc3sc-unit-40-wg-star.netdev.drv' on nix-store.geninf.io:50051 unit-40-wg-star.netdev> build01: building w55v5w3jw0pv8p531rv27qllla0jc3sc-unit-40-wg-star.netdev.drv unit-40-wg-star.network> structuredAttrs is enabled unit-40-wg-star.network> structuredAttrs is enabled unit-dm-wg-star-wg-star-reconfig.path> structuredAttrs is enabled unit-40-wg-star.netdev> structuredAttrs is enabled unit-dm-wg-star-wg-star-reconfig.path> structuredAttrs is enabled unit-dm-wg-star-wg-star-publish.timer> structuredAttrs is enabled unit-99-ethernet-default-dhcp.network> structuredAttrs is enabled unit-99-wireless-client-dhcp.network> structuredAttrs is enabled unit-40-wg-star.netdev> structuredAttrs is enabled building '/nix/store/sxr2lzfh7l53v509mxilk22wyjnbs743-nix.conf.drv' on nix-store.geninf.io:50051 nix.conf> build01: building sxr2lzfh7l53v509mxilk22wyjnbs743-nix.conf.drv dm.toml> structuredAttrs is enabled dm.toml> structuredAttrs is enabled nix.conf> Validating generated nix.conf dm.toml> structuredAttrs is enabled vars-check-dm-wireguard-star> Running vars check using Nix-based executor... vars-check-dm-wireguard-star> ✓ All vars checks completed successfully building '/nix/store/8sgcdl6s5ca0isdvi96rjrqcdcqkmgg6-etc-sysctl.d-60-nixos.conf.drv' on nix-store.geninf.io:50051 etc-sysctl.d-60-nixos.conf> build01: building 8sgcdl6s5ca0isdvi96rjrqcdcqkmgg6-etc-sysctl.d-60-nixos.conf.drv building '/nix/store/17n07jv6jvam4as7mcmvdijgd2c0yc96-users-groups.json.drv' on nix-store.geninf.io:50051 users-groups.json> build01: building 17n07jv6jvam4as7mcmvdijgd2c0yc96-users-groups.json.drv building '/nix/store/59rarkz592z7fq6i9valh3jym80vwdrj-unit-dm-wg-star-wg-star-publish.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.service> build01: building 59rarkz592z7fq6i9valh3jym80vwdrj-unit-dm-wg-star-wg-star-publish.service.drv building '/nix/store/xc9nzh0xv0i0wnnazggpj9vhi5yhnw7c-python3.14-nixos-test-lib-1.0.0.drv' on nix-store.geninf.io:50051 python3.14-nixos-test-lib-1.0.0> build01: building xc9nzh0xv0i0wnnazggpj9vhi5yhnw7c-python3.14-nixos-test-lib-1.0.0.drv building '/nix/store/nagza86q45wys0d5jh8dvvxwfixfnwg5-unit-dm-wg-star-wg-star-publish.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.service> build01: building nagza86q45wys0d5jh8dvvxwfixfnwg5-unit-dm-wg-star-wg-star-publish.service.drv building '/nix/store/lxkn1msxrap3j472dyw6hq1lk55dml8p-X-Restart-Triggers-nix-daemon.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-nix-daemon> build01: building lxkn1msxrap3j472dyw6hq1lk55dml8p-X-Restart-Triggers-nix-daemon.drv unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled building '/nix/store/wvz1diqwi5p7ffbdz87b1qildirmfsx8-unit-dm-wg-star-wg-star-reconfig.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.service> build01: building wvz1diqwi5p7ffbdz87b1qildirmfsx8-unit-dm-wg-star-wg-star-reconfig.service.drv building '/nix/store/1sfmmjp8acz5jkaddq1xzaz7jcyx98ia-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-data-mesher> build01: building 1sfmmjp8acz5jkaddq1xzaz7jcyx98ia-X-Restart-Triggers-data-mesher.drv building '/nix/store/47vb773q2gadgk77yksrwhp0adqykgz9-decrypt-age-secrets.drv' on nix-store.geninf.io:50051 decrypt-age-secrets> build01: building 47vb773q2gadgk77yksrwhp0adqykgz9-decrypt-age-secrets.drv unit-dm-wg-star-wg-star-reconfig.service> structuredAttrs is enabled python3.14-nixos-test-lib-1.0.0> Sourcing python-remove-tests-dir-hook python3.14-nixos-test-lib-1.0.0> Sourcing python-catch-conflicts-hook.sh python3.14-nixos-test-lib-1.0.0> Sourcing python-remove-bin-bytecode-hook.sh python3.14-nixos-test-lib-1.0.0> Sourcing pypa-build-hook python3.14-nixos-test-lib-1.0.0> Using pypaBuildPhase python3.14-nixos-test-lib-1.0.0> Sourcing python-runtime-deps-check-hook python3.14-nixos-test-lib-1.0.0> Using pythonRuntimeDepsCheckHook python3.14-nixos-test-lib-1.0.0> Sourcing pypa-install-hook python3.14-nixos-test-lib-1.0.0> Using pypaInstallPhase python3.14-nixos-test-lib-1.0.0> Sourcing python-imports-check-hook.sh python3.14-nixos-test-lib-1.0.0> Using pythonImportsCheckPhase python3.14-nixos-test-lib-1.0.0> Sourcing python-namespaces-hook python3.14-nixos-test-lib-1.0.0> Sourcing python-catch-conflicts-hook.sh python3.14-nixos-test-lib-1.0.0> Running phase: unpackPhase python3.14-nixos-test-lib-1.0.0> unpacking source archive /nix/store/7x1aaz1lsjpbv64fvax4ffnqjg8a37ks-source python3.14-nixos-test-lib-1.0.0> source root is source python3.14-nixos-test-lib-1.0.0> setting SOURCE_DATE_EPOCH to timestamp 315619200 of file "source/pyproject.toml" python3.14-nixos-test-lib-1.0.0> Running phase: patchPhase python3.14-nixos-test-lib-1.0.0> substituteStream() in derivation python3.14-nixos-test-lib-1.0.0: WARNING: '--replace' is deprecated, use --replace-{fail,warn,quiet}. (file 'nixos_test_lib/nix_setup.py') python3.14-nixos-test-lib-1.0.0> Running phase: updateAutotoolsGnuConfigScriptsPhase python3.14-nixos-test-lib-1.0.0> Running phase: configurePhase python3.14-nixos-test-lib-1.0.0> no configure script, doing nothing python3.14-nixos-test-lib-1.0.0> Running phase: buildPhase python3.14-nixos-test-lib-1.0.0> Executing pypaBuildPhase python3.14-nixos-test-lib-1.0.0> Creating a wheel... python3.14-nixos-test-lib-1.0.0> pypa build flags: --no-isolation --outdir dist/ --wheel building '/nix/store/911xb828a5iahyp8vhdhp99gx0gpzwiw-unit-dm-wg-star-wg-star-reconfig.service.drv' on nix-store.geninf.io:50051 building '/nix/store/3flb4ly4vvzz23a4nhmqw47m2w728zdk-firewall-reload.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-reconfig.service> build01: building 911xb828a5iahyp8vhdhp99gx0gpzwiw-unit-dm-wg-star-wg-star-reconfig.service.drv firewall-reload> build01: building 3flb4ly4vvzz23a4nhmqw47m2w728zdk-firewall-reload.drv unit-dm-wg-star-wg-star-reconfig.service> structuredAttrs is enabled building '/nix/store/cbwfzi076kgn4l9xw3xw0xcxsniql28w-firewall-reload.drv' on nix-store.geninf.io:50051 firewall-reload> build01: building cbwfzi076kgn4l9xw3xw0xcxsniql28w-firewall-reload.drv building '/nix/store/92dv8v9jgyasw4bs4in8r1bxs58h97p7-X-Restart-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-systemd-networkd> build01: building 92dv8v9jgyasw4bs4in8r1bxs58h97p7-X-Restart-Triggers-systemd-networkd.drv python3.14-nixos-test-lib-1.0.0> * Getting build dependencies for wheel... building '/nix/store/ndwcm0xbx51xdkd3yn645vjq39ah7xv0-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-data-mesher> build01: building ndwcm0xbx51xdkd3yn645vjq39ah7xv0-X-Restart-Triggers-data-mesher.drv building '/nix/store/y6s2k1wr8hz7dbxggz0zr6gq83k6xfl8-decrypt-age-secrets.drv' on nix-store.geninf.io:50051 decrypt-age-secrets> build01: building y6s2k1wr8hz7dbxggz0zr6gq83k6xfl8-decrypt-age-secrets.drv building '/nix/store/ydn0c2y07pamz0sja6142mvswbhviws3-decrypt-age-secrets.drv' on nix-store.geninf.io:50051 decrypt-age-secrets> build01: building ydn0c2y07pamz0sja6142mvswbhviws3-decrypt-age-secrets.drv python3.14-nixos-test-lib-1.0.0> running egg_info python3.14-nixos-test-lib-1.0.0> creating nixos_test_lib.egg-info python3.14-nixos-test-lib-1.0.0> writing nixos_test_lib.egg-info/PKG-INFO python3.14-nixos-test-lib-1.0.0> writing dependency_links to nixos_test_lib.egg-info/dependency_links.txt python3.14-nixos-test-lib-1.0.0> writing requirements to nixos_test_lib.egg-info/requires.txt python3.14-nixos-test-lib-1.0.0> writing top-level names to nixos_test_lib.egg-info/top_level.txt python3.14-nixos-test-lib-1.0.0> writing manifest file 'nixos_test_lib.egg-info/SOURCES.txt' python3.14-nixos-test-lib-1.0.0> reading manifest file 'nixos_test_lib.egg-info/SOURCES.txt' python3.14-nixos-test-lib-1.0.0> writing manifest file 'nixos_test_lib.egg-info/SOURCES.txt' python3.14-nixos-test-lib-1.0.0> * Building wheel... building '/nix/store/715qq9fil2yp3g4h1mhli221r7y9g4hs-X-Reload-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Reload-Triggers-systemd-networkd> build01: building 715qq9fil2yp3g4h1mhli221r7y9g4hs-X-Reload-Triggers-systemd-networkd.drv building '/nix/store/yai2pgbh0iiflzlb4mb6x9n41qq4qgnc-X-Restart-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-systemd-networkd> build01: building yai2pgbh0iiflzlb4mb6x9n41qq4qgnc-X-Restart-Triggers-systemd-networkd.drv python3.14-nixos-test-lib-1.0.0> running bdist_wheel python3.14-nixos-test-lib-1.0.0> running build python3.14-nixos-test-lib-1.0.0> running build_py python3.14-nixos-test-lib-1.0.0> creating build/lib/nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/__init__.py -> build/lib/nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/ssh.py -> build/lib/nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/port.py -> build/lib/nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/nix_setup.py -> build/lib/nixos_test_lib python3.14-nixos-test-lib-1.0.0> running egg_info python3.14-nixos-test-lib-1.0.0> writing nixos_test_lib.egg-info/PKG-INFO python3.14-nixos-test-lib-1.0.0> writing dependency_links to nixos_test_lib.egg-info/dependency_links.txt python3.14-nixos-test-lib-1.0.0> writing requirements to nixos_test_lib.egg-info/requires.txt python3.14-nixos-test-lib-1.0.0> writing top-level names to nixos_test_lib.egg-info/top_level.txt python3.14-nixos-test-lib-1.0.0> reading manifest file 'nixos_test_lib.egg-info/SOURCES.txt' python3.14-nixos-test-lib-1.0.0> writing manifest file 'nixos_test_lib.egg-info/SOURCES.txt' python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/py.typed -> build/lib/nixos_test_lib python3.14-nixos-test-lib-1.0.0> installing to build/bdist.linux-aarch64/wheel python3.14-nixos-test-lib-1.0.0> running install python3.14-nixos-test-lib-1.0.0> running install_lib python3.14-nixos-test-lib-1.0.0> creating build/bdist.linux-aarch64/wheel python3.14-nixos-test-lib-1.0.0> creating build/bdist.linux-aarch64/wheel/nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/__init__.py -> build/bdist.linux-aarch64/wheel/./nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/ssh.py -> build/bdist.linux-aarch64/wheel/./nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/port.py -> build/bdist.linux-aarch64/wheel/./nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/nix_setup.py -> build/bdist.linux-aarch64/wheel/./nixos_test_lib python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/py.typed -> build/bdist.linux-aarch64/wheel/./nixos_test_lib python3.14-nixos-test-lib-1.0.0> running install_egg_info python3.14-nixos-test-lib-1.0.0> Copying nixos_test_lib.egg-info to build/bdist.linux-aarch64/wheel/./nixos_test_lib-1.0.0-py3.14.egg-info python3.14-nixos-test-lib-1.0.0> running install_scripts python3.14-nixos-test-lib-1.0.0> creating build/bdist.linux-aarch64/wheel/nixos_test_lib-1.0.0.dist-info/WHEEL python3.14-nixos-test-lib-1.0.0> creating '/build/source/dist/.tmp-vos_wk8h/nixos_test_lib-1.0.0-py3-none-any.whl' and adding 'build/bdist.linux-aarch64/wheel' to it python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/__init__.py' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/nix_setup.py' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/port.py' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/py.typed' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/ssh.py' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/METADATA' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/WHEEL' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/top_level.txt' python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/RECORD' python3.14-nixos-test-lib-1.0.0> removing build/bdist.linux-aarch64/wheel python3.14-nixos-test-lib-1.0.0> Successfully built nixos_test_lib-1.0.0-py3-none-any.whl python3.14-nixos-test-lib-1.0.0> Finished creating a wheel... python3.14-nixos-test-lib-1.0.0> Finished executing pypaBuildPhase python3.14-nixos-test-lib-1.0.0> Running phase: pythonRuntimeDepsCheckHook python3.14-nixos-test-lib-1.0.0> Executing pythonRuntimeDepsCheck python3.14-nixos-test-lib-1.0.0> Checking runtime dependencies for nixos_test_lib-1.0.0-py3-none-any.whl python3.14-nixos-test-lib-1.0.0> Finished executing pythonRuntimeDepsCheck python3.14-nixos-test-lib-1.0.0> Running phase: installPhase python3.14-nixos-test-lib-1.0.0> Executing pypaInstallPhase python3.14-nixos-test-lib-1.0.0> Successfully installed nixos_test_lib-1.0.0-py3-none-any.whl python3.14-nixos-test-lib-1.0.0> Finished executing pypaInstallPhase python3.14-nixos-test-lib-1.0.0> Running phase: pythonOutputDistPhase python3.14-nixos-test-lib-1.0.0> Executing pythonOutputDistPhase python3.14-nixos-test-lib-1.0.0> Finished executing pythonOutputDistPhase python3.14-nixos-test-lib-1.0.0> Running phase: fixupPhase python3.14-nixos-test-lib-1.0.0> shrinking RPATHs of ELF executables and libraries in /nix/store/4kyzlhg6b2g2p8m6ifzliz6lc5g1wp49-python3.14-nixos-test-lib-1.0.0 python3.14-nixos-test-lib-1.0.0> checking for references to /build/ in /nix/store/4kyzlhg6b2g2p8m6ifzliz6lc5g1wp49-python3.14-nixos-test-lib-1.0.0... python3.14-nixos-test-lib-1.0.0> patching script interpreter paths in /nix/store/4kyzlhg6b2g2p8m6ifzliz6lc5g1wp49-python3.14-nixos-test-lib-1.0.0 python3.14-nixos-test-lib-1.0.0> stripping (with command strip and flags -S -p) in /nix/store/4kyzlhg6b2g2p8m6ifzliz6lc5g1wp49-python3.14-nixos-test-lib-1.0.0/lib python3.14-nixos-test-lib-1.0.0> shrinking RPATHs of ELF executables and libraries in /nix/store/v47qayg1w8962xf169b7r2a4vnlfhfa0-python3.14-nixos-test-lib-1.0.0-dist python3.14-nixos-test-lib-1.0.0> checking for references to /build/ in /nix/store/v47qayg1w8962xf169b7r2a4vnlfhfa0-python3.14-nixos-test-lib-1.0.0-dist... python3.14-nixos-test-lib-1.0.0> patching script interpreter paths in /nix/store/v47qayg1w8962xf169b7r2a4vnlfhfa0-python3.14-nixos-test-lib-1.0.0-dist python3.14-nixos-test-lib-1.0.0> Executing pythonRemoveTestsDir python3.14-nixos-test-lib-1.0.0> Finished executing pythonRemoveTestsDir python3.14-nixos-test-lib-1.0.0> Running phase: pythonCatchConflictsPhase python3.14-nixos-test-lib-1.0.0> Running phase: pythonRemoveBinBytecodePhase python3.14-nixos-test-lib-1.0.0> Running phase: pythonImportsCheckPhase python3.14-nixos-test-lib-1.0.0> Executing pythonImportsCheckPhase building '/nix/store/lhvjv1vikqhkm1vdv0kx36r1s0w4xgbm-X-Reload-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Reload-Triggers-systemd-networkd> build01: building lhvjv1vikqhkm1vdv0kx36r1s0w4xgbm-X-Reload-Triggers-systemd-networkd.drv building '/nix/store/pm99lvnb1bdizpryas4krv3c4ayc7q35-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-data-mesher> build01: building pm99lvnb1bdizpryas4krv3c4ayc7q35-X-Restart-Triggers-data-mesher.drv building '/nix/store/92i5jja2dfvwags61bal1k6ayybsrlll-system-path.drv' on nix-store.geninf.io:50051 system-path> build01: building 92i5jja2dfvwags61bal1k6ayybsrlll-system-path.drv building '/nix/store/rcykws7h0mylac55kd5ys88znpf6y25q-X-Reload-Triggers-systemd-networkd.drv' on nix-store.geninf.io:50051 X-Reload-Triggers-systemd-networkd> build01: building rcykws7h0mylac55kd5ys88znpf6y25q-X-Reload-Triggers-systemd-networkd.drv building '/nix/store/fykqkbycp1053j97ml7m8dvcdh7l7w98-unit-dm-wg-star-wg-star-publish.service.drv' on nix-store.geninf.io:50051 unit-dm-wg-star-wg-star-publish.service> build01: building fykqkbycp1053j97ml7m8dvcdh7l7w98-unit-dm-wg-star-wg-star-publish.service.drv system-path> structuredAttrs is enabled unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled building '/nix/store/b0haywwrgi1ifjb1zz5sq4cl4l8zj0pa-X-Restart-Triggers-systemd-sysctl.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-systemd-sysctl> build01: building b0haywwrgi1ifjb1zz5sq4cl4l8zj0pa-X-Restart-Triggers-systemd-sysctl.drv building '/nix/store/07pandag1s0sm85b5cc6b8k629iw73kb-hosts.drv' on nix-store.geninf.io:50051 hosts> build01: building 07pandag1s0sm85b5cc6b8k629iw73kb-hosts.drv building '/nix/store/05x39sf44gc5flx2j5mzg3yjz06czx2x-hosts.drv' on nix-store.geninf.io:50051 hosts> build01: building 05x39sf44gc5flx2j5mzg3yjz06czx2x-hosts.drv building '/nix/store/li78nsbx4hwghnm0paggha0k5w1n7zjy-hosts.drv' on nix-store.geninf.io:50051 hosts> build01: building li78nsbx4hwghnm0paggha0k5w1n7zjy-hosts.drv system-path> created 1726 symlinks in user environment building '/nix/store/p59vc11z3qpirajbvf8fbyilmywpig7p-unit-nix-daemon.service.drv' on nix-store.geninf.io:50051 unit-nix-daemon.service> build01: building p59vc11z3qpirajbvf8fbyilmywpig7p-unit-nix-daemon.service.drv unit-nix-daemon.service> structuredAttrs is enabled building '/nix/store/0c9cd2p772ckp9bkf574x2l3xiywbi7f-unit-data-mesher.service.drv' on nix-store.geninf.io:50051 unit-data-mesher.service> build01: building 0c9cd2p772ckp9bkf574x2l3xiywbi7f-unit-data-mesher.service.drv unit-data-mesher.service> structuredAttrs is enabled building '/nix/store/jasqcrs9k4j2xckjgmfap73jx7cpakcr-unit-firewall.service.drv' on nix-store.geninf.io:50051 unit-firewall.service> build01: building jasqcrs9k4j2xckjgmfap73jx7cpakcr-unit-firewall.service.drv unit-firewall.service> structuredAttrs is enabled building '/nix/store/r7nd7kbj9qmaagibhqc787nar2hxcpq7-unit-firewall.service.drv' on nix-store.geninf.io:50051 unit-firewall.service> build01: building r7nd7kbj9qmaagibhqc787nar2hxcpq7-unit-firewall.service.drv unit-firewall.service> structuredAttrs is enabled building '/nix/store/xccs8m4x2fwkydmv6iz4aspjx9g64i7b-unit-data-mesher.service.drv' on nix-store.geninf.io:50051 unit-data-mesher.service> build01: building xccs8m4x2fwkydmv6iz4aspjx9g64i7b-unit-data-mesher.service.drv unit-data-mesher.service> structuredAttrs is enabled building '/nix/store/y87jfh3avqypmgs8943hnq9khkjw2g95-dry-activate.drv' on nix-store.geninf.io:50051 dry-activate> build01: building y87jfh3avqypmgs8943hnq9khkjw2g95-dry-activate.drv building '/nix/store/qx487647kjd397w9k1mkny11pxxsd7bq-nixos-test-driver-1.1.drv' on nix-store.geninf.io:50051 nixos-test-driver-1.1> attached to the build already running on this worker building '/nix/store/4n7fslk09wlcw0bbnsb7bz7h0i0pqqrf-dry-activate.drv' on nix-store.geninf.io:50051 dry-activate> build01: building 4n7fslk09wlcw0bbnsb7bz7h0i0pqqrf-dry-activate.drv building '/nix/store/xs1i4v1kpagk46ld8vsxn1aa4igagshg-dry-activate.drv' on nix-store.geninf.io:50051 dry-activate> build01: building xs1i4v1kpagk46ld8vsxn1aa4igagshg-dry-activate.drv building '/nix/store/sfasj9h3qms3kidyb1anf3nyswy2hsl6-unit-systemd-networkd.service.drv' on nix-store.geninf.io:50051 unit-systemd-networkd.service> build01: building sfasj9h3qms3kidyb1anf3nyswy2hsl6-unit-systemd-networkd.service.drv building '/nix/store/w9dmbh0j55gy4ckqkgs23d8dj4wq6ik0-unit-systemd-networkd.service.drv' on nix-store.geninf.io:50051 unit-systemd-networkd.service> build01: building w9dmbh0j55gy4ckqkgs23d8dj4wq6ik0-unit-systemd-networkd.service.drv unit-systemd-networkd.service> structuredAttrs is enabled unit-systemd-networkd.service> structuredAttrs is enabled building '/nix/store/as89ic2k9ni36cgbqccnr42jp79hlh16-unit-systemd-networkd.service.drv' on nix-store.geninf.io:50051 unit-systemd-networkd.service> build01: building as89ic2k9ni36cgbqccnr42jp79hlh16-unit-systemd-networkd.service.drv building '/nix/store/plj2api2pawxfhy75s08wr73rjh8rflr-unit-data-mesher.service.drv' on nix-store.geninf.io:50051 unit-data-mesher.service> build01: building plj2api2pawxfhy75s08wr73rjh8rflr-unit-data-mesher.service.drv building '/nix/store/cw1w948ipxhfm4yrigk08k80haaqgfp7-unit-systemd-sysctl.service.drv' on nix-store.geninf.io:50051 unit-systemd-sysctl.service> build01: building cw1w948ipxhfm4yrigk08k80haaqgfp7-unit-systemd-sysctl.service.drv unit-systemd-networkd.service> structuredAttrs is enabled unit-data-mesher.service> structuredAttrs is enabled unit-systemd-sysctl.service> structuredAttrs is enabled building '/nix/store/hcfqbj3ljpkg01wbl1q0gfdsv6c4lvn8-dbus-1.drv' on nix-store.geninf.io:50051 dbus-1> build01: building hcfqbj3ljpkg01wbl1q0gfdsv6c4lvn8-dbus-1.drv nixos-test-driver-1.1> build01: building qx487647kjd397w9k1mkny11pxxsd7bq-nixos-test-driver-1.1.drv nixos-test-driver-1.1> Sourcing python-remove-tests-dir-hook nixos-test-driver-1.1> Sourcing python-catch-conflicts-hook.sh nixos-test-driver-1.1> Sourcing python-remove-bin-bytecode-hook.sh nixos-test-driver-1.1> Sourcing pypa-build-hook nixos-test-driver-1.1> Using pypaBuildPhase nixos-test-driver-1.1> Sourcing python-runtime-deps-check-hook nixos-test-driver-1.1> Using pythonRuntimeDepsCheckHook nixos-test-driver-1.1> Sourcing pypa-install-hook nixos-test-driver-1.1> Using pypaInstallPhase nixos-test-driver-1.1> Sourcing python-imports-check-hook.sh nixos-test-driver-1.1> Using pythonImportsCheckPhase nixos-test-driver-1.1> Sourcing python-namespaces-hook nixos-test-driver-1.1> Sourcing python-catch-conflicts-hook.sh nixos-test-driver-1.1> Running phase: unpackPhase nixos-test-driver-1.1> unpacking source archive /nix/store/49i1gz8kibmrg74pqm4piss72cjxa7mh-src nixos-test-driver-1.1> source root is src nixos-test-driver-1.1> setting SOURCE_DATE_EPOCH to timestamp 315619200 of file "src/test_driver/vlan.py" nixos-test-driver-1.1> Running phase: patchPhase nixos-test-driver-1.1> Running phase: updateAutotoolsGnuConfigScriptsPhase nixos-test-driver-1.1> Running phase: configurePhase nixos-test-driver-1.1> no configure script, doing nothing nixos-test-driver-1.1> Running phase: buildPhase nixos-test-driver-1.1> Executing pypaBuildPhase nixos-test-driver-1.1> Creating a wheel... nixos-test-driver-1.1> pypa build flags: --no-isolation --outdir dist/ --wheel nixos-test-driver-1.1> * Getting build dependencies for wheel... nixos-test-driver-1.1> running egg_info nixos-test-driver-1.1> creating nixos_test_driver.egg-info nixos-test-driver-1.1> writing nixos_test_driver.egg-info/PKG-INFO nixos-test-driver-1.1> writing dependency_links to nixos_test_driver.egg-info/dependency_links.txt nixos-test-driver-1.1> writing entry points to nixos_test_driver.egg-info/entry_points.txt nixos-test-driver-1.1> writing top-level names to nixos_test_driver.egg-info/top_level.txt nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> reading manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> * Building wheel... nixos-test-driver-1.1> running bdist_wheel nixos-test-driver-1.1> running build nixos-test-driver-1.1> running build_py nixos-test-driver-1.1> creating build/lib/test_driver nixos-test-driver-1.1> copying test_driver/debug.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/__init__.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/errors.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/logger.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/driver.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/vlan.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/efi.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/duration.py -> build/lib/test_driver nixos-test-driver-1.1> copying test_driver/polling_condition.py -> build/lib/test_driver nixos-test-driver-1.1> creating build/lib/test_driver/machine nixos-test-driver-1.1> copying test_driver/machine/__init__.py -> build/lib/test_driver/machine nixos-test-driver-1.1> copying test_driver/machine/ocr.py -> build/lib/test_driver/machine nixos-test-driver-1.1> copying test_driver/machine/qmp.py -> build/lib/test_driver/machine nixos-test-driver-1.1> running egg_info nixos-test-driver-1.1> writing nixos_test_driver.egg-info/PKG-INFO nixos-test-driver-1.1> writing dependency_links to nixos_test_driver.egg-info/dependency_links.txt nixos-test-driver-1.1> writing entry points to nixos_test_driver.egg-info/entry_points.txt nixos-test-driver-1.1> writing top-level names to nixos_test_driver.egg-info/top_level.txt nixos-test-driver-1.1> reading manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt' nixos-test-driver-1.1> copying test_driver/py.typed -> build/lib/test_driver nixos-test-driver-1.1> installing to build/bdist.linux-aarch64/wheel nixos-test-driver-1.1> running install nixos-test-driver-1.1> running install_lib nixos-test-driver-1.1> creating build/bdist.linux-aarch64/wheel nixos-test-driver-1.1> creating build/bdist.linux-aarch64/wheel/test_driver nixos-test-driver-1.1> copying build/lib/test_driver/debug.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/__init__.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/errors.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> creating build/bdist.linux-aarch64/wheel/test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/machine/__init__.py -> build/bdist.linux-aarch64/wheel/./test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/machine/ocr.py -> build/bdist.linux-aarch64/wheel/./test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/machine/qmp.py -> build/bdist.linux-aarch64/wheel/./test_driver/machine nixos-test-driver-1.1> copying build/lib/test_driver/logger.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/driver.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/vlan.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/efi.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/duration.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/py.typed -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> copying build/lib/test_driver/polling_condition.py -> build/bdist.linux-aarch64/wheel/./test_driver nixos-test-driver-1.1> running install_egg_info nixos-test-driver-1.1> Copying nixos_test_driver.egg-info to build/bdist.linux-aarch64/wheel/./nixos_test_driver-0.0.0-py3.14.egg-info nixos-test-driver-1.1> running install_scripts nixos-test-driver-1.1> creating build/bdist.linux-aarch64/wheel/nixos_test_driver-0.0.0.dist-info/WHEEL nixos-test-driver-1.1> creating '/build/src/dist/.tmp-pk84oqen/nixos_test_driver-0.0.0-py3-none-any.whl' and adding 'build/bdist.linux-aarch64/wheel' to it nixos-test-driver-1.1> adding 'test_driver/__init__.py' nixos-test-driver-1.1> adding 'test_driver/debug.py' nixos-test-driver-1.1> adding 'test_driver/driver.py' nixos-test-driver-1.1> adding 'test_driver/duration.py' nixos-test-driver-1.1> adding 'test_driver/efi.py' nixos-test-driver-1.1> adding 'test_driver/errors.py' nixos-test-driver-1.1> adding 'test_driver/logger.py' nixos-test-driver-1.1> adding 'test_driver/polling_condition.py' nixos-test-driver-1.1> adding 'test_driver/py.typed' nixos-test-driver-1.1> adding 'test_driver/vlan.py' nixos-test-driver-1.1> adding 'test_driver/machine/__init__.py' nixos-test-driver-1.1> adding 'test_driver/machine/ocr.py' nixos-test-driver-1.1> adding 'test_driver/machine/qmp.py' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/METADATA' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/WHEEL' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/entry_points.txt' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/top_level.txt' nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/RECORD' nixos-test-driver-1.1> removing build/bdist.linux-aarch64/wheel nixos-test-driver-1.1> Successfully built nixos_test_driver-0.0.0-py3-none-any.whl nixos-test-driver-1.1> Finished creating a wheel... nixos-test-driver-1.1> Finished executing pypaBuildPhase nixos-test-driver-1.1> Running phase: pythonRuntimeDepsCheckHook nixos-test-driver-1.1> Executing pythonRuntimeDepsCheck nixos-test-driver-1.1> Checking runtime dependencies for nixos_test_driver-0.0.0-py3-none-any.whl nixos-test-driver-1.1> Finished executing pythonRuntimeDepsCheck nixos-test-driver-1.1> Running phase: installPhase nixos-test-driver-1.1> Executing pypaInstallPhase nixos-test-driver-1.1> Successfully installed nixos_test_driver-0.0.0-py3-none-any.whl nixos-test-driver-1.1> Finished executing pypaInstallPhase nixos-test-driver-1.1> Running phase: pythonOutputDistPhase nixos-test-driver-1.1> Executing pythonOutputDistPhase nixos-test-driver-1.1> Finished executing pythonOutputDistPhase nixos-test-driver-1.1> Running phase: fixupPhase nixos-test-driver-1.1> shrinking RPATHs of ELF executables and libraries in /nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1 nixos-test-driver-1.1> checking for references to /build/ in /nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1... nixos-test-driver-1.1> patching script interpreter paths in /nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1 nixos-test-driver-1.1> stripping (with command strip and flags -S -p) in /nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib /nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/bin nixos-test-driver-1.1> shrinking RPATHs of ELF executables and libraries in /nix/store/5xbb2kwxlx6yxzaw9vw021ys00l46qfv-nixos-test-driver-1.1-dist nixos-test-driver-1.1> checking for references to /build/ in /nix/store/5xbb2kwxlx6yxzaw9vw021ys00l46qfv-nixos-test-driver-1.1-dist... nixos-test-driver-1.1> patching script interpreter paths in /nix/store/5xbb2kwxlx6yxzaw9vw021ys00l46qfv-nixos-test-driver-1.1-dist nixos-test-driver-1.1> Rewriting #!/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7/bin/python3.14 to #!/nix/store/3inr3nxzqarhb9rh1z7s2s20j8c0395h-python3-3.14.7 nixos-test-driver-1.1> wrapping `/nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/bin/nixos-test-driver'... nixos-test-driver-1.1> Executing pythonRemoveTestsDir nixos-test-driver-1.1> Finished executing pythonRemoveTestsDir nixos-test-driver-1.1> Running phase: installCheckPhase nixos-test-driver-1.1> ## run ty nixos-test-driver-1.1> All checks passed! nixos-test-driver-1.1> ## run ruff check nixos-test-driver-1.1> All checks passed! nixos-test-driver-1.1> ## run ruff format nixos-test-driver-1.1> 25 files already formatted nixos-test-driver-1.1> Running phase: pythonCatchConflictsPhase nixos-test-driver-1.1> Running phase: pythonRemoveBinBytecodePhase nixos-test-driver-1.1> Running phase: pythonImportsCheckPhase nixos-test-driver-1.1> Executing pythonImportsCheckPhase building '/nix/store/0d2jilhvy7cgdp7ffbj2yb1ra2y4ikjg-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051 X-Restart-Triggers-dbus-broker> build01: building 0d2jilhvy7cgdp7ffbj2yb1ra2y4ikjg-X-Restart-Triggers-dbus-broker.drv building '/nix/store/dphm06p3zkm0yzrm1ja64khyn21jdhw2-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building dphm06p3zkm0yzrm1ja64khyn21jdhw2-unit-dbus-broker.service.drv building '/nix/store/01jgawpfjh3c5hjfd6ykbbifgr7aq9ny-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051 unit-dbus-broker.service> build01: building 01jgawpfjh3c5hjfd6ykbbifgr7aq9ny-unit-dbus-broker.service.drv unit-dbus-broker.service> structuredAttrs is enabled unit-dbus-broker.service> structuredAttrs is enabled building '/nix/store/h44ld5571mq6rfnrbw3pjsvy6zkly6k1-user-units.drv' on nix-store.geninf.io:50051 user-units> build01: building h44ld5571mq6rfnrbw3pjsvy6zkly6k1-user-units.drv building '/nix/store/1gmxcsbdsrqmralzicxlwjwhn85mray8-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building 1gmxcsbdsrqmralzicxlwjwhn85mray8-system-units.drv building '/nix/store/9iw6sid1gcsvhdyha6y9fwghc98qfmf9-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building 9iw6sid1gcsvhdyha6y9fwghc98qfmf9-system-units.drv building '/nix/store/5wm7cy19f6r04dbrvggfcwq31kiscrn9-system-units.drv' on nix-store.geninf.io:50051 system-units> build01: building 5wm7cy19f6r04dbrvggfcwq31kiscrn9-system-units.drv building '/nix/store/0bak7x3wgnvfzpp0nh9cm2qj5cx2zcqc-etc.drv' on nix-store.geninf.io:50051 etc> build01: building 0bak7x3wgnvfzpp0nh9cm2qj5cx2zcqc-etc.drv building '/nix/store/4d3p57rrzh98shsr36gz650nsky75nfi-etc.drv' on nix-store.geninf.io:50051 etc> build01: building 4d3p57rrzh98shsr36gz650nsky75nfi-etc.drv building '/nix/store/hq3kdjrnmfzsdn5sz1f5k7a7yfg3n203-etc.drv' on nix-store.geninf.io:50051 etc> build01: building hq3kdjrnmfzsdn5sz1f5k7a7yfg3n203-etc.drv building '/nix/store/inkgkj0kqx29vnkh4rws5hwsn8ljdzj1-activate.drv' on nix-store.geninf.io:50051 activate> build01: building inkgkj0kqx29vnkh4rws5hwsn8ljdzj1-activate.drv building '/nix/store/0ca6wfgf143vk4wa5qjkmisf956fy0l8-activate.drv' on nix-store.geninf.io:50051 activate> build01: building 0ca6wfgf143vk4wa5qjkmisf956fy0l8-activate.drv building '/nix/store/3bb1pd0y32dw5y508w1gfp5s0sddpgc3-activate.drv' on nix-store.geninf.io:50051 activate> build01: building 3bb1pd0y32dw5y508w1gfp5s0sddpgc3-activate.drv building '/nix/store/hx76bqqf4cbwqzac6a81k8bvkbw55hfa-nixos-system-peer2-test.drv' on nix-store.geninf.io:50051 nixos-system-peer2-test> build01: building hx76bqqf4cbwqzac6a81k8bvkbw55hfa-nixos-system-peer2-test.drv nixos-system-peer2-test> structuredAttrs is enabled building '/nix/store/zl1n6b6vdkgd1nqlx3hd95b07xm4dccx-nixos-system-controller-test.drv' on nix-store.geninf.io:50051 nixos-system-controller-test> build01: building zl1n6b6vdkgd1nqlx3hd95b07xm4dccx-nixos-system-controller-test.drv nixos-system-controller-test> structuredAttrs is enabled building '/nix/store/xpc383hxi5mp981r3vs7fhdc311yfanj-nixos-system-peer1-test.drv' on nix-store.geninf.io:50051 nixos-system-peer1-test> build01: building xpc383hxi5mp981r3vs7fhdc311yfanj-nixos-system-peer1-test.drv nixos-system-peer1-test> structuredAttrs is enabled building '/nix/store/gbh6h3c45kisf08z6clg3j010q5a4m3k-run-peer2-nspawn.drv' on nix-store.geninf.io:50051 run-peer2-nspawn> build01: building gbh6h3c45kisf08z6clg3j010q5a4m3k-run-peer2-nspawn.drv building '/nix/store/3yqv47ivncdwi2aazhd1fxzy4gc4pw5q-run-controller-nspawn.drv' on nix-store.geninf.io:50051 run-controller-nspawn> build01: building 3yqv47ivncdwi2aazhd1fxzy4gc4pw5q-run-controller-nspawn.drv building '/nix/store/8s0ifqghhkbh2wqqbf31w40bi5rdrwwz-run-peer1-nspawn.drv' on nix-store.geninf.io:50051 run-peer1-nspawn> build01: building 8s0ifqghhkbh2wqqbf31w40bi5rdrwwz-run-peer1-nspawn.drv building '/nix/store/dymsqlj0nvsyzyppbwv5m9qfsly43hfv-driverConfiguration.json.drv' on nix-store.geninf.io:50051 driverConfiguration.json> build01: building dymsqlj0nvsyzyppbwv5m9qfsly43hfv-driverConfiguration.json.drv driverConfiguration.json> structuredAttrs is enabled building '/nix/store/z1lsbfyq6pdfgp632x8876xp8z46zi0k-nixos-test-driver-dm-wireguard-star.drv' on nix-store.geninf.io:50051 nixos-test-driver-dm-wireguard-star> build01: building z1lsbfyq6pdfgp632x8876xp8z46zi0k-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/nbjw4cx2i6xbr8sm9hhn4djacczgqf8g-container-test-run-dm-wireguard-star.drv' on nix-store.geninf.io:50051 container-test-run-dm-wireguard-star> build01: building nbjw4cx2i6xbr8sm9hhn4djacczgqf8g-container-test-run-dm-wireguard-star.drv container-test-run-dm-wireguard-star> Machine state will be reset. To keep it, pass --keep-machine-state container-test-run-dm-wireguard-star> start all VLans container-test-run-dm-wireguard-star> (finished: start all VLans, in 0.00 seconds) container-test-run-dm-wireguard-star> container-test-run-dm-wireguard-star> Test will time out and terminate in 3600.0 seconds container-test-run-dm-wireguard-star> run the VM test script container-test-run-dm-wireguard-star> additionally exposed symbols: container-test-run-dm-wireguard-star> controller, peer1, peer2, container-test-run-dm-wireguard-star> vlan1, container-test-run-dm-wireguard-star> start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh container-test-run-dm-wireguard-star> controller: systemd-nspawn running (pid 50) container-test-run-dm-wireguard-star> controller: Waiting for journal at /build/vm-state-controller/var/log/journal... container-test-run-dm-wireguard-star> peer1: systemd-nspawn running (pid 53) container-test-run-dm-wireguard-star> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal... container-test-run-dm-wireguard-star> controller: waiting for unit data-mesher.service container-test-run-dm-wireguard-star> nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(controller): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-dm-wireguard-star> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE container-test-run-dm-wireguard-star> nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. container-test-run-dm-wireguard-star> ░ Spawning container controller on /build/vm-state-controller. container-test-run-dm-wireguard-star> ░ Spawning container peer1 on /build/vm-state-peer1. container-test-run-dm-wireguard-star> controller # No journal boot entry found for the specified boot (+0). container-test-run-dm-wireguard-star> peer1 # No journal boot entry found for the specified boot (+0). container-test-run-dm-wireguard-star> peer1 # [136991.642173] peer1 systemd-journald[97]: Journal started container-test-run-dm-wireguard-star> peer1 # [136991.642223] peer1 systemd-journald[97]: Runtime Journal (/run/log/journal/0339db5b87414b01bbeb3cd1de8d78ae) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> peer1 # [136991.651544] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer1 # [136991.660451] peer1 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer1 # [136991.661616] peer1 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer1 # [136991.662491] peer1 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer1 # [136991.671043] peer1 systemd-journald[97]: Time spent on flushing to /var/log/journal/0339db5b87414b01bbeb3cd1de8d78ae is 1.354ms for 6 entries. container-test-run-dm-wireguard-star> peer1 # [136991.671043] peer1 systemd-journald[97]: System Journal (/var/log/journal/0339db5b87414b01bbeb3cd1de8d78ae) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer1 # [136991.678840] peer1 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer1 # [136991.679572] peer1 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer1 # [136991.679720] peer1 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer1 # [136991.680718] peer1 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer1 # [136991.680774] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [136991.681659] peer1 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer1 # [136991.681696] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer1 # [136991.689357] peer1 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer1 # [136991.690810] peer1 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer1 # [136991.705890] peer1 systemd-tmpfiles[141]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136991.706089] peer1 systemd-tmpfiles[141]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136991.706218] peer1 systemd-tmpfiles[141]: fchmod() of /var/log/journal/0339db5b87414b01bbeb3cd1de8d78ae failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136991.706406] peer1 systemd-tmpfiles[141]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136991.707851] peer1 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer1 # [136991.708878] peer1 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer1 # [136991.709636] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer1 # [136991.721535] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer1 # [136991.727867] peer1 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer1 # [136991.729201] peer1 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer1 # [136991.741452] peer1 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer1 # [136991.812337] peer1 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> peer1 # [136991.812511] peer1 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer1 # [136991.812732] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer1 # [136991.813802] peer1 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> peer1 # [136992.194898] peer1 systemd-networkd[214]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.194987] peer1 systemd-networkd[214]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.202132] peer1 systemd-networkd[214]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer1 # [136992.202303] peer1 systemd-networkd[214]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. container-test-run-dm-wireguard-star> peer1 # [136992.202446] peer1 systemd-networkd[214]: lo: Link UP container-test-run-dm-wireguard-star> peer1 # [136992.202452] peer1 systemd-networkd[214]: lo: Gained carrier container-test-run-dm-wireguard-star> peer1 # [136992.202646] peer1 systemd-networkd[214]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer1 # [136992.203089] peer1 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer1 # [136992.203761] peer1 systemd-networkd[214]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer1 # [136992.204106] peer1 systemd-networkd[214]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer1 # [136992.204484] peer1 systemd-networkd[214]: eth1: Link UP container-test-run-dm-wireguard-star> peer1 # [136992.204697] peer1 systemd-networkd[214]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer1 # [136992.204882] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer1 # [136992.248958] peer1 systemd-networkd[214]: wg-star: Link UP container-test-run-dm-wireguard-star> peer1 # [136992.248964] peer1 systemd-networkd[214]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer1 # [136992.250549] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer1 # [136992.282275] peer1 systemd-resolved[120]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer1 # [136992.282287] peer1 systemd-resolved[120]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer1 # [136992.282290] peer1 systemd-resolved[120]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer1 # [136992.282324] peer1 systemd-resolved[120]: 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 # [136991.647045] controller systemd-journald[105]: Journal started container-test-run-dm-wireguard-star> peer1 # [136992.304818] peer1 systemd-resolved[120]: Using system hostname 'peer1'. container-test-run-dm-wireguard-star> controller # [136991.647103] controller systemd-journald[105]: Runtime Journal (/run/log/journal/337028c1143749e79dcaa91ab5a171f8) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> peer1 # [136992.306449] peer1 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> controller # [136991.651512] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer1 # [136992.306586] peer1 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> controller # [136991.660311] controller systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer1 # [136992.306720] peer1 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> controller # [136991.661322] controller systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer1 # [136992.306894] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> controller # [136991.662013] controller systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer1 # [136992.306966] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> controller # [136991.670806] controller systemd-journald[105]: Time spent on flushing to /var/log/journal/337028c1143749e79dcaa91ab5a171f8 is 1.187ms for 6 entries. container-test-run-dm-wireguard-star> peer1 # [136992.307033] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [136991.670806] controller systemd-journald[105]: System Journal (/var/log/journal/337028c1143749e79dcaa91ab5a171f8) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer1 # [136992.307076] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> controller # [136991.679280] controller systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer1 # [136992.307118] peer1 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> controller # [136991.679517] controller systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer1 # [136992.307197] peer1 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> controller # [136991.679603] controller systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer1 # [136992.307433] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> controller # [136991.680353] controller systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer1 # [136992.307657] peer1 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> controller # [136991.680400] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer1 # [136992.307891] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> controller # [136991.681265] controller systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer1 # [136992.307941] peer1 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> controller # [136991.681301] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer1 # [136992.308045] peer1 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> controller # [136991.687732] controller systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer1 # [136992.310493] peer1 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> controller # [136991.688749] controller systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer1 # [136992.311953] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> controller # [136991.707514] controller systemd-tmpfiles[149]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.313498] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> controller # [136991.707710] controller systemd-tmpfiles[149]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.315834] peer1 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> controller # [136991.707859] controller systemd-tmpfiles[149]: fchmod() of /var/log/journal/337028c1143749e79dcaa91ab5a171f8 failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.334268] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> controller # [136991.708194] controller systemd-tmpfiles[149]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.449686] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> controller # [136991.709937] controller systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer1 # [136992.449916] peer1 nsncd[221]: Oct 17 23:26:37.421 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 # [136991.711046] controller systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer1 # [136992.449782] peer1 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> controller # [136991.711788] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer1 # [136992.449891] peer1 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> controller # [136991.725041] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer1 # [136992.452080] peer1 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> controller # [136991.732192] controller systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer1 # [136992.453829] peer1 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> controller # [136991.733428] controller systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer1 # [136992.504032] peer1 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> controller # [136991.744393] controller systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer1 # [136992.505353] peer1 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> controller # [136991.817223] controller systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> peer1 # [136992.505397] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> controller # [136991.817386] controller systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer1 # [136992.505419] peer1 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> controller # [136991.817620] controller systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer1 # [136992.545516] peer1 dbus-broker-launch[222]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> controller # [136991.818631] controller systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> peer1 # [136992.546421] peer1 dbus-broker-launch[222]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> controller # [136992.170593] controller systemd-networkd[224]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.546421] peer1 dbus-broker-launch[222]: Invalid user-name in /nix/store/sc7aavcyam9q7nbjzhwaxdpvfqz19nxc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> controller # [136992.170681] controller systemd-networkd[224]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer1 # [136992.546975] peer1 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> controller # [136992.177937] controller systemd-networkd[224]: /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 # [136992.555328] peer1 dbus-broker-launch[222]: Ready container-test-run-dm-wireguard-star> controller # [136992.178107] controller systemd-networkd[224]: /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 # [136992.584012] peer1 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> controller # [136992.178253] controller systemd-networkd[224]: lo: Link UP container-test-run-dm-wireguard-star> peer1 # [136992.632342] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [136992.178258] controller systemd-networkd[224]: lo: Gained carrier container-test-run-dm-wireguard-star> controller # [136992.178437] controller systemd-networkd[224]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> controller # [136992.178902] controller systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> controller # [136992.185313] controller systemd-networkd[224]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> controller # [136992.185654] controller systemd-networkd[224]: wg-star: netdev ready container-test-run-dm-wireguard-star> controller # [136992.185966] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> controller # [136992.186291] controller systemd-networkd[224]: eth1: Link UP container-test-run-dm-wireguard-star> controller # [136992.186510] controller systemd-networkd[224]: eth1: Gained carrier container-test-run-dm-wireguard-star> controller # [136992.241004] controller systemd-networkd[224]: wg-star: Link UP container-test-run-dm-wireguard-star> controller # [136992.241011] controller systemd-networkd[224]: wg-star: Gained carrier container-test-run-dm-wireguard-star> controller # [136992.241788] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> controller # [136992.275613] controller systemd-resolved[128]: Positive Trust Anchors: container-test-run-dm-wireguard-star> controller # [136992.275626] controller systemd-resolved[128]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> controller # [136992.275629] controller systemd-resolved[128]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> controller # [136992.275664] controller systemd-resolved[128]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test container-test-run-dm-wireguard-star> controller # [136992.297746] controller systemd-resolved[128]: Using system hostname 'controller'. container-test-run-dm-wireguard-star> controller # [136992.299372] controller systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> controller # [136992.299529] controller systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> controller # [136992.299666] controller systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> controller # [136992.299843] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher. container-test-run-dm-wireguard-star> controller # [136992.299920] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> controller # [136992.299970] controller systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> controller # [136992.300050] controller systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> controller # [136992.300131] controller systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> controller # [136992.300371] controller systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> controller # [136992.300593] controller systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> controller # [136992.300816] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> controller # [136992.300869] controller systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> controller # [136992.300949] controller systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> controller # [136992.306240] controller systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> controller # [136992.307932] controller systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> controller # [136992.309636] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> controller # [136992.312531] controller systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> controller # [136992.330493] controller systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> controller # [136992.449870] controller nsncd[231]: Oct 17 23:26:37.421 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 # [136992.449700] controller systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> controller # [136992.449804] controller systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> controller # [136992.449913] controller systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> controller # [136992.452598] controller systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> controller # [136992.454038] controller systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> controller # [136992.502956] controller systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> controller # [136992.504297] controller systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> controller # [136992.504355] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> controller # [136992.504383] controller systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> controller # [136992.584561] controller systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> controller # [136992.607110] controller dbus-broker-launch[232]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> controller # [136992.608172] controller dbus-broker-launch[232]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> controller # [136992.608172] controller dbus-broker-launch[232]: Invalid user-name in /nix/store/sc7aavcyam9q7nbjzhwaxdpvfqz19nxc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> controller # [136992.608724] controller systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> controller # [136992.618535] controller dbus-broker-launch[232]: Ready container-test-run-dm-wireguard-star> controller # [136992.642726] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [136992.866368] controller data-mesher[229]: time=2026-10-17T23:26:37.838Z 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 # [136992.867378] controller data-mesher[229]: time=2026-10-17T23:26:37.839Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk: [/dns/controller.clan/tcp/7946]} {12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c: [/dns/peer1.clan/tcp/7946]} {12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> controller # [136992.867378] controller data-mesher[229]: time=2026-10-17T23:26:37.839Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> controller # [136992.868472] controller data-mesher[229]: time=2026-10-17T23:26:37.840Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> controller # [136992.868581] controller data-mesher[229]: time=2026-10-17T23:26:37.840Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> controller # [136992.872449] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="libp2p host created" peer_id=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk 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::b202:e78a:7743:ac5/tcp/7946]" container-test-run-dm-wireguard-star> controller # [136992.872489] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> controller # [136992.872489] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> controller # [136992.872489] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> controller # [136992.872489] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> controller # [136992.872595] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> controller # [136992.872696] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> controller # [136992.872734] controller data-mesher[229]: time=2026-10-17T23:26:37.844Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> controller # [136993.042306] controller systemd-logind[249]: New seat seat0. container-test-run-dm-wireguard-star> controller # [136993.043080] controller systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> controller # [136993.052919] controller systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> controller # [136993.064567] controller systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [136993.064741] controller systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> peer1 # [136992.852117] peer1 data-mesher[219]: time=2026-10-17T23:26:37.823Z 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 # [136992.853197] peer1 data-mesher[219]: time=2026-10-17T23:26:37.825Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk: [/dns/controller.clan/tcp/7946]} {12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c: [/dns/peer1.clan/tcp/7946]} {12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> peer1 # [136992.853197] peer1 data-mesher[219]: time=2026-10-17T23:26:37.825Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer1 # [136992.855775] peer1 data-mesher[219]: time=2026-10-17T23:26:37.827Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer1 # [136992.855884] peer1 data-mesher[219]: time=2026-10-17T23:26:37.827Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer1 # [136992.859854] peer1 data-mesher[219]: time=2026-10-17T23:26:37.831Z level=INFO msg="libp2p host created" peer_id=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c 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::4ba5:b0d0:89d4:774b/tcp/7946]" container-test-run-dm-wireguard-star> peer1 # [136992.859892] peer1 data-mesher[219]: time=2026-10-17T23:26:37.831Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer1 # [136992.859892] peer1 data-mesher[219]: time=2026-10-17T23:26:37.831Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer1 # [136992.859892] peer1 data-mesher[219]: time=2026-10-17T23:26:37.831Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer1 # [136992.859954] peer1 data-mesher[219]: time=2026-10-17T23:26:37.831Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer1 # [136992.860078] peer1 data-mesher[219]: time=2026-10-17T23:26:37.831Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer1 # [136992.860394] peer1 data-mesher[219]: time=2026-10-17T23:26:37.832Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer1 # [136992.860438] peer1 data-mesher[219]: time=2026-10-17T23:26:37.832Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer1 # [136993.055462] peer1 systemd-logind[239]: New seat seat0. container-test-run-dm-wireguard-star> peer1 # [136993.055800] peer1 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer1 # [136993.057393] peer1 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer1 # [136993.069173] peer1 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [136993.069262] peer1 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> controller # [136993.820424] controller systemd-networkd[224]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> peer1 # [136993.980159] peer1 systemd-networkd[214]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> peer1 # [136997.883673] peer1 data-mesher[219]: time=2026-10-17T23:26:42.855Z level=INFO msg="peer connected" peer_id=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk remote_addr=/ip6/2001:db8:1::1/tcp/7946 container-test-run-dm-wireguard-star> controller # [136997.881808] controller data-mesher[229]: time=2026-10-17T23:26:42.853Z level=INFO msg="peer connected" peer_id=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c remote_addr=/ip6/2001:db8:1::2/tcp/7946 container-test-run-dm-wireguard-star> controller: still waiting for container 'controller' to reach ready state... container-test-run-dm-wireguard-star> controller # [137002.861371] controller data-mesher[229]: time=2026-10-17T23:26:47.833Z level=INFO msg="received state sync from peer" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137002.861371] controller data-mesher[229]: time=2026-10-17T23:26:47.833Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137002.873175] controller data-mesher[229]: time=2026-10-17T23:26:47.844Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> controller # [137002.873348] controller data-mesher[229]: time=2026-10-17T23:26:47.845Z level=DEBUG msg="initiating state exchange" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137002.874149] controller data-mesher[229]: time=2026-10-17T23:26:47.845Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137002.874149] controller data-mesher[229]: time=2026-10-17T23:26:47.846Z level=INFO msg="state exchange complete" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137002.874262] controller data-mesher[229]: time=2026-10-17T23:26:47.846Z level=INFO msg="server started" container-test-run-dm-wireguard-star> controller # [137002.874405] controller data-mesher[229]: time=2026-10-17T23:26:47.846Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> controller # [137002.874496] controller systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> controller # [137002.904614] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher... container-test-run-dm-wireguard-star> controller # [137002.943332] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [137002.951281] controller data-mesher[229]: time=2026-10-17T23:26:47.923Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204 container-test-run-dm-wireguard-star> controller # [137002.951440] controller dm-wg-star-publish[296]: Status: 204 No Content container-test-run-dm-wireguard-star> controller # [137002.954673] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher. container-test-run-dm-wireguard-star> controller # [137002.955262] controller systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> controller # [137003.039245] controller dm-wg-star-reconfig[323]: No peer data available yet, skipping container-test-run-dm-wireguard-star> controller # [137003.040304] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [137003.060407] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> controller # [137003.060826] controller systemd[1]: Startup finished in 11.818s. container-test-run-dm-wireguard-star> peer1 # [137002.860579] peer1 data-mesher[219]: time=2026-10-17T23:26:47.832Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer1 # [137002.861329] peer1 data-mesher[219]: time=2026-10-17T23:26:47.832Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137002.861679] peer1 data-mesher[219]: time=2026-10-17T23:26:47.833Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137002.861679] peer1 data-mesher[219]: time=2026-10-17T23:26:47.833Z level=INFO msg="state exchange complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137002.861803] peer1 data-mesher[219]: time=2026-10-17T23:26:47.833Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer1 # [137002.861914] peer1 data-mesher[219]: time=2026-10-17T23:26:47.833Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer1 # [137002.861991] peer1 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer1 # [137002.863664] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer1 # [137002.873855] peer1 data-mesher[219]: time=2026-10-17T23:26:47.845Z level=INFO msg="received state sync from peer" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137002.873855] peer1 data-mesher[219]: time=2026-10-17T23:26:47.845Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137003.025443] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [137003.026415] peer1 data-mesher[219]: time=2026-10-17T23:26:47.998Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k status=204 container-test-run-dm-wireguard-star> peer1 # [137003.026540] peer1 dm-wg-star-publish[285]: Status: 204 No Content container-test-run-dm-wireguard-star> peer1 # [137003.029248] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [137003.060307] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer1 # [137003.060928] peer1 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> peer1 # [137003.149527] peer1 dm-wg-star-reconfig[313]: No controller data available yet, skipping container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 12.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/8xlk88ia8b5yg9kfrg3skndwzgphipvr-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/8xlk88ia8b5yg9kfrg3skndwzgphipvr-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 # [137003.150842] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [137003.150998] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer1 # [137003.151573] peer1 systemd[1]: Startup finished in 11.923s. container-test-run-dm-wireguard-star> controller # [137007.863252] controller data-mesher[229]: time=2026-10-17T23:26:52.835Z level=INFO msg="received state sync from peer" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137007.863252] controller data-mesher[229]: time=2026-10-17T23:26:52.835Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137007.863991] controller data-mesher[229]: time=2026-10-17T23:26:52.835Z level=DEBUG msg="new file detected" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> controller # [137007.864100] controller data-mesher[229]: time=2026-10-17T23:26:52.835Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> controller # [137007.864188] controller data-mesher[229]: time=2026-10-17T23:26:52.836Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k signed_at="2026-10-17 23:26:47.992 +0000 UTC" signed_by="TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM/xJi3k=" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137007.865175] controller data-mesher[229]: time=2026-10-17T23:26:52.837Z level=INFO msg="received file request" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [137007.867442] controller data-mesher[229]: time=2026-10-17T23:26:52.839Z level=INFO msg="file transfer complete" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [137007.872575] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [137007.875126] controller data-mesher[229]: time=2026-10-17T23:26:52.846Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [137007.875126] controller data-mesher[229]: time=2026-10-17T23:26:52.847Z level=DEBUG msg="initiating state exchange" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137007.876199] controller data-mesher[229]: time=2026-10-17T23:26:52.848Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137007.876643] controller data-mesher[229]: time=2026-10-17T23:26:52.848Z level=INFO msg="download complete" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k signed_at="2026-10-17 23:26:47.992 +0000 UTC" signed_by="TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM/xJi3k=" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c written=true elapsed=12.489172ms container-test-run-dm-wireguard-star> controller # [137007.876857] controller data-mesher[229]: time=2026-10-17T23:26:52.848Z level=INFO msg="state exchange complete" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137007.876915] controller data-mesher[229]: time=2026-10-17T23:26:52.848Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [137008.023092] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [137008.023246] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher. container-test-run-dm-wireguard-star> peer1 # [137007.862392] peer1 data-mesher[219]: time=2026-10-17T23:26:52.834Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [137007.863072] peer1 data-mesher[219]: time=2026-10-17T23:26:52.834Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137007.864241] peer1 data-mesher[219]: time=2026-10-17T23:26:52.836Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137007.864241] peer1 data-mesher[219]: time=2026-10-17T23:26:52.836Z level=DEBUG msg="new file detected" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [137007.864367] peer1 data-mesher[219]: time=2026-10-17T23:26:52.836Z level=INFO msg="state exchange complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137007.864367] peer1 data-mesher[219]: time=2026-10-17T23:26:52.836Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [137007.864367] peer1 data-mesher[219]: time=2026-10-17T23:26:52.836Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [137007.864514] peer1 data-mesher[219]: time=2026-10-17T23:26:52.836Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-17 23:26:47.909 +0000 UTC" signed_by="vc56aLNWYkmsIoih9geuAdIW+euKM0kLd62fxmFBpJE=" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137007.864724] peer1 data-mesher[219]: time=2026-10-17T23:26:52.836Z level=INFO msg="received file request" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> peer1 # [137007.867155] peer1 data-mesher[219]: time=2026-10-17T23:26:52.838Z level=INFO msg="file transfer complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> peer1 # [137007.871188] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer1 # [137007.875780] peer1 data-mesher[219]: time=2026-10-17T23:26:52.847Z level=INFO msg="received state sync from peer" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137007.875780] peer1 data-mesher[219]: time=2026-10-17T23:26:52.847Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137007.875923] peer1 data-mesher[219]: time=2026-10-17T23:26:52.847Z level=DEBUG msg="new file detected" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [137007.875923] peer1 data-mesher[219]: time=2026-10-17T23:26:52.847Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-17 23:26:47.909 +0000 UTC" signed_by="vc56aLNWYkmsIoih9geuAdIW+euKM0kLd62fxmFBpJE=" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk written=true elapsed=11.539158ms container-test-run-dm-wireguard-star> peer1 # [137008.018113] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer1 # [137008.018510] 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::b202:e78a:7743:0ac5 container-test-run-dm-wireguard-star> controller # [137012.865429] controller data-mesher[229]: time=2026-10-17T23:26:57.837Z level=INFO msg="received state sync from peer" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137012.865429] controller data-mesher[229]: time=2026-10-17T23:26:57.837Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137012.877754] controller data-mesher[229]: time=2026-10-17T23:26:57.849Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [137012.877851] controller data-mesher[229]: time=2026-10-17T23:26:57.849Z level=DEBUG msg="initiating state exchange" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137012.879460] controller data-mesher[229]: time=2026-10-17T23:26:57.851Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137012.880098] controller data-mesher[229]: time=2026-10-17T23:26:57.851Z level=INFO msg="state exchange complete" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137012.880190] controller data-mesher[229]: time=2026-10-17T23:26:57.851Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [137012.864655] peer1 data-mesher[219]: time=2026-10-17T23:26:57.836Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [137012.865398] peer1 data-mesher[219]: time=2026-10-17T23:26:57.836Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137012.866443] peer1 data-mesher[219]: time=2026-10-17T23:26:57.838Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137012.867052] peer1 data-mesher[219]: time=2026-10-17T23:26:57.838Z level=INFO msg="state exchange complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137012.867121] peer1 data-mesher[219]: time=2026-10-17T23:26:57.838Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [137012.878496] peer1 data-mesher[219]: time=2026-10-17T23:26:57.850Z level=INFO msg="received state sync from peer" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137012.878573] peer1 data-mesher[219]: time=2026-10-17T23:26:57.850Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::b202:e78a:7743:0ac5, in 6.02 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::4ba5:b0d0:89d4:774b container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::4ba5:b0d0:89d4:774b, 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 784) 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 boot entry found for the specified boot (+0). container-test-run-dm-wireguard-star> peer2 # [137015.480182] peer2 systemd-journald[94]: Journal started container-test-run-dm-wireguard-star> peer2 # [137015.480240] peer2 systemd-journald[94]: Runtime Journal (/run/log/journal/8b387664a4b144ce81b847700604729b) is 8M, max 2.5G, 2.4G free. container-test-run-dm-wireguard-star> peer2 # [137015.483200] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. container-test-run-dm-wireguard-star> peer2 # [137015.492556] peer2 systemd[1]: Starting Flush Journal to Persistent Storage... container-test-run-dm-wireguard-star> peer2 # [137015.493513] peer2 systemd[1]: Starting Network Name Resolution... container-test-run-dm-wireguard-star> peer2 # [137015.494470] peer2 systemd[1]: Starting Create Static Device Nodes in /dev... container-test-run-dm-wireguard-star> peer2 # [137015.501746] peer2 systemd-journald[94]: Time spent on flushing to /var/log/journal/8b387664a4b144ce81b847700604729b is 1.097ms for 6 entries. container-test-run-dm-wireguard-star> peer2 # [137015.501746] peer2 systemd-journald[94]: System Journal (/var/log/journal/8b387664a4b144ce81b847700604729b) is 8M, max 4G, 3.9G free. container-test-run-dm-wireguard-star> peer2 # [137015.510196] peer2 systemd[1]: Finished Create Static Device Nodes in /dev. container-test-run-dm-wireguard-star> peer2 # [137015.510414] peer2 systemd[1]: Reached target Preparation for Local File Systems. container-test-run-dm-wireguard-star> peer2 # [137015.510491] peer2 systemd[1]: Reached target Local File Systems. container-test-run-dm-wireguard-star> peer2 # [137015.511227] peer2 systemd[1]: Listening on Boot Loader Control Service Socket. container-test-run-dm-wireguard-star> peer2 # [137015.511269] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [137015.512075] peer2 systemd[1]: Starting Save Transient machine-id to Disk... container-test-run-dm-wireguard-star> peer2 # [137015.512107] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys container-test-run-dm-wireguard-star> peer2 # [137015.513509] peer2 systemd[1]: Finished Flush Journal to Persistent Storage. container-test-run-dm-wireguard-star> peer2 # [137015.514332] peer2 systemd[1]: Starting Create System Files and Directories... container-test-run-dm-wireguard-star> peer2 # [137015.530519] peer2 systemd[1]: Finished Save Transient machine-id to Disk. container-test-run-dm-wireguard-star> peer2 # [137015.532326] peer2 systemd-tmpfiles[133]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [137015.532524] peer2 systemd-tmpfiles[133]: fchmod() of /var/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [137015.532656] peer2 systemd-tmpfiles[133]: fchmod() of /var/log/journal/8b387664a4b144ce81b847700604729b failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [137015.532853] peer2 systemd-tmpfiles[133]: fchmod() of /run/log/journal failed: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [137015.534291] peer2 systemd[1]: Finished Create System Files and Directories. container-test-run-dm-wireguard-star> peer2 # [137015.535303] peer2 systemd[1]: Starting Rebuild Journal Catalog... container-test-run-dm-wireguard-star> peer2 # [137015.536067] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... container-test-run-dm-wireguard-star> peer2 # [137015.549561] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. container-test-run-dm-wireguard-star> peer2 # [137015.558748] peer2 systemd[1]: Finished Rebuild Journal Catalog. container-test-run-dm-wireguard-star> peer2 # [137015.559971] peer2 systemd[1]: Starting Update is Completed... container-test-run-dm-wireguard-star> peer2 # [137015.570977] peer2 systemd[1]: Finished Update is Completed. container-test-run-dm-wireguard-star> peer2 # [137015.647065] peer2 systemd[1]: Finished Firewall. container-test-run-dm-wireguard-star> peer2 # [137015.647806] peer2 systemd[1]: Reached target Preparation for Network. container-test-run-dm-wireguard-star> peer2 # [137015.648326] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket. container-test-run-dm-wireguard-star> peer2 # [137015.649879] peer2 systemd[1]: Starting Network Management... container-test-run-dm-wireguard-star> peer2 # [137016.006564] peer2 systemd-networkd[212]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [137016.006654] peer2 systemd-networkd[212]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted container-test-run-dm-wireguard-star> peer2 # [137016.013579] peer2 systemd-networkd[212]: /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 # [137016.013746] peer2 systemd-networkd[212]: /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 # [137016.013890] peer2 systemd-networkd[212]: lo: Link UP container-test-run-dm-wireguard-star> peer2 # [137016.013893] peer2 systemd-networkd[212]: lo: Gained carrier container-test-run-dm-wireguard-star> peer2 # [137016.014084] peer2 systemd-networkd[212]: eth1: Configuring with /etc/systemd/network/40-eth1.network. container-test-run-dm-wireguard-star> peer2 # [137016.014624] peer2 systemd[1]: Started Network Management. container-test-run-dm-wireguard-star> peer2 # [137016.015702] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd... container-test-run-dm-wireguard-star> peer2 # [137016.015771] peer2 systemd-networkd[212]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network. container-test-run-dm-wireguard-star> peer2 # [137016.016108] peer2 systemd-networkd[212]: wg-star: netdev ready container-test-run-dm-wireguard-star> peer2 # [137016.016509] peer2 systemd-networkd[212]: eth1: Link UP container-test-run-dm-wireguard-star> peer2 # [137016.016726] peer2 systemd-networkd[212]: eth1: Gained carrier container-test-run-dm-wireguard-star> peer2 # [137016.041290] peer2 systemd-resolved[116]: Positive Trust Anchors: container-test-run-dm-wireguard-star> peer2 # [137016.041301] peer2 systemd-resolved[116]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d container-test-run-dm-wireguard-star> peer2 # [137016.041306] peer2 systemd-resolved[116]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 container-test-run-dm-wireguard-star> peer2 # [137016.041340] peer2 systemd-resolved[116]: 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 # [137016.052580] peer2 systemd-networkd[212]: wg-star: Link UP container-test-run-dm-wireguard-star> peer2 # [137016.052586] peer2 systemd-networkd[212]: wg-star: Gained carrier container-test-run-dm-wireguard-star> peer2 # [137016.062949] peer2 systemd-resolved[116]: Using system hostname 'peer2'. container-test-run-dm-wireguard-star> peer2 # [137016.064566] peer2 systemd[1]: Started Network Name Resolution. container-test-run-dm-wireguard-star> peer2 # [137016.064706] peer2 systemd[1]: Reached target Network. container-test-run-dm-wireguard-star> peer2 # [137016.064815] peer2 systemd[1]: Reached target System Initialization. container-test-run-dm-wireguard-star> peer2 # [137016.064998] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher. container-test-run-dm-wireguard-star> peer2 # [137016.065058] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer. container-test-run-dm-wireguard-star> peer2 # [137016.065102] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container container-test-run-dm-wireguard-star> peer2 # [137016.065144] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories. container-test-run-dm-wireguard-star> peer2 # [137016.065190] peer2 systemd[1]: Reached target Path Units. container-test-run-dm-wireguard-star> peer2 # [137016.065269] peer2 systemd[1]: Reached target Timer Units. container-test-run-dm-wireguard-star> peer2 # [137016.065513] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket. container-test-run-dm-wireguard-star> peer2 # [137016.065742] peer2 systemd[1]: Listening on Nix Daemon Socket. container-test-run-dm-wireguard-star> peer2 # [137016.065975] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. container-test-run-dm-wireguard-star> peer2 # [137016.066023] peer2 systemd[1]: Reached target Socket Units. container-test-run-dm-wireguard-star> peer2 # [137016.066099] peer2 systemd[1]: Reached target Basic System. container-test-run-dm-wireguard-star> peer2 # [137016.068311] peer2 systemd[1]: Starting data mesher daemon... container-test-run-dm-wireguard-star> peer2 # [137016.069740] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database... container-test-run-dm-wireguard-star> peer2 # [137016.071091] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... container-test-run-dm-wireguard-star> peer2 # [137016.071594] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd. container-test-run-dm-wireguard-star> peer2 # [137016.075299] peer2 systemd[1]: Starting D-Bus System Message Bus... container-test-run-dm-wireguard-star> peer2 # [137016.091054] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database. container-test-run-dm-wireguard-star> peer2 # [137016.172303] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd). container-test-run-dm-wireguard-star> peer2 # [137016.172444] peer2 nsncd[218]: Oct 17 23:27:01.144 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 # [137016.172423] peer2 systemd[1]: Reached target Host and Network Name Lookups. container-test-run-dm-wireguard-star> peer2 # [137016.172525] peer2 systemd[1]: Reached target User and Group Name Lookups. container-test-run-dm-wireguard-star> peer2 # [137016.174715] peer2 systemd[1]: Starting User Login Management... container-test-run-dm-wireguard-star> peer2 # [137016.176105] peer2 systemd[1]: Starting Permit User Sessions... container-test-run-dm-wireguard-star> peer2 # [137016.220180] peer2 systemd[1]: Finished Permit User Sessions. container-test-run-dm-wireguard-star> peer2 # [137016.221816] peer2 systemd[1]: Started Console Getty. container-test-run-dm-wireguard-star> peer2 # [137016.221889] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 container-test-run-dm-wireguard-star> peer2 # [137016.221929] peer2 systemd[1]: Reached target Login Prompts. container-test-run-dm-wireguard-star> peer2 # [137016.236306] peer2 dbus-broker-launch[221]: Looking up NSS user entry for 'systemd-timesync'... container-test-run-dm-wireguard-star> peer2 # [137016.237947] peer2 dbus-broker-launch[221]: NSS returned no entry for 'systemd-timesync' container-test-run-dm-wireguard-star> peer2 # [137016.237947] peer2 dbus-broker-launch[221]: Invalid user-name in /nix/store/sc7aavcyam9q7nbjzhwaxdpvfqz19nxc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" container-test-run-dm-wireguard-star> peer2 # [137016.238384] peer2 systemd[1]: Started D-Bus System Message Bus. container-test-run-dm-wireguard-star> peer2 # [137016.246361] peer2 dbus-broker-launch[221]: Ready container-test-run-dm-wireguard-star> peer2 # [137016.454435] peer2 data-mesher[216]: time=2026-10-17T23:27:01.426Z 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 # [137016.455538] peer2 data-mesher[216]: time=2026-10-17T23:27:01.427Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk: [/dns/controller.clan/tcp/7946]} {12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c: [/dns/peer1.clan/tcp/7946]} {12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m container-test-run-dm-wireguard-star> peer2 # [137016.455538] peer2 data-mesher[216]: time=2026-10-17T23:27:01.427Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml container-test-run-dm-wireguard-star> peer2 # [137016.456826] peer2 data-mesher[216]: time=2026-10-17T23:27:01.428Z level=INFO msg="checking file integrity" container-test-run-dm-wireguard-star> peer2 # [137016.456940] peer2 data-mesher[216]: time=2026-10-17T23:27:01.428Z level=INFO msg="file integrity check complete" container-test-run-dm-wireguard-star> peer2 # [137016.460645] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="libp2p host created" peer_id=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m 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::eb76:9549:900d:8944/tcp/7946]" container-test-run-dm-wireguard-star> peer2 # [137016.460723] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="registered HTTP route" method=GET path=/files container-test-run-dm-wireguard-star> peer2 # [137016.460723] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name container-test-run-dm-wireguard-star> peer2 # [137016.460723] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name container-test-run-dm-wireguard-star> peer2 # [137016.460723] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="starting server" container-test-run-dm-wireguard-star> peer2 # [137016.460902] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="waiting for DHT to populate" delay=10s container-test-run-dm-wireguard-star> peer2 # [137016.460954] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="HTTP server listening" address=[::1]:7331 container-test-run-dm-wireguard-star> peer2 # [137016.461167] peer2 data-mesher[216]: time=2026-10-17T23:27:01.432Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331 container-test-run-dm-wireguard-star> peer2 # [137016.468504] peer2 data-mesher[216]: time=2026-10-17T23:27:01.440Z level=INFO msg="peer connected" peer_id=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c remote_addr=/ip4/192.168.1.2/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [137016.475339] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [137016.476229] peer2 data-mesher[216]: time=2026-10-17T23:27:01.448Z level=INFO msg="peer connected" peer_id=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk remote_addr=/ip4/192.168.1.1/tcp/7946 container-test-run-dm-wireguard-star> peer1 # [137016.469505] peer1 data-mesher[219]: time=2026-10-17T23:27:01.441Z level=INFO msg="peer connected" peer_id=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [137016.612843] peer2 systemd-logind[237]: New seat seat0. container-test-run-dm-wireguard-star> peer2 # [137016.613061] peer2 systemd[1]: Started User Login Management. container-test-run-dm-wireguard-star> peer2 # [137016.615302] peer2 systemd[1]: Starting linger-users.service... container-test-run-dm-wireguard-star> peer2 # [137016.666642] peer2 systemd[1]: linger-users.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [137016.666969] peer2 systemd[1]: Finished linger-users.service. container-test-run-dm-wireguard-star> controller # [137016.478131] controller data-mesher[229]: time=2026-10-17T23:27:01.449Z level=INFO msg="peer connected" peer_id=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m remote_addr=/ip4/192.168.1.3/tcp/7946 container-test-run-dm-wireguard-star> peer2 # [137017.404371] peer2 systemd-networkd[212]: eth1: Gained IPv6LL container-test-run-dm-wireguard-star> controller # [137017.868861] controller data-mesher[229]: time=2026-10-17T23:27:02.840Z level=INFO msg="received state sync from peer" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137017.868861] controller data-mesher[229]: time=2026-10-17T23:27:02.840Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137017.881211] controller data-mesher[229]: time=2026-10-17T23:27:02.852Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [137017.881211] controller data-mesher[229]: time=2026-10-17T23:27:02.852Z level=DEBUG msg="initiating state exchange" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137017.882588] controller data-mesher[229]: time=2026-10-17T23:27:02.854Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137017.883177] controller data-mesher[229]: time=2026-10-17T23:27:02.855Z level=INFO msg="state exchange complete" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c timeout=5s container-test-run-dm-wireguard-star> controller # [137017.883244] controller data-mesher[229]: time=2026-10-17T23:27:02.855Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [137017.868117] peer1 data-mesher[219]: time=2026-10-17T23:27:02.839Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [137017.868810] peer1 data-mesher[219]: time=2026-10-17T23:27:02.840Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137017.869863] peer1 data-mesher[219]: time=2026-10-17T23:27:02.841Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137017.870449] peer1 data-mesher[219]: time=2026-10-17T23:27:02.842Z level=INFO msg="state exchange complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137017.870532] peer1 data-mesher[219]: time=2026-10-17T23:27:02.842Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1 # [137017.881587] peer1 data-mesher[219]: time=2026-10-17T23:27:02.853Z level=INFO msg="received state sync from peer" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137017.881587] peer1 data-mesher[219]: time=2026-10-17T23:27:02.853Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> controller # [137022.871854] controller data-mesher[229]: time=2026-10-17T23:27:07.843Z level=INFO msg="received state sync from peer" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137022.871854] controller data-mesher[229]: time=2026-10-17T23:27:07.843Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137022.883523] controller data-mesher[229]: time=2026-10-17T23:27:07.855Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [137022.883523] controller data-mesher[229]: time=2026-10-17T23:27:07.855Z level=DEBUG msg="initiating state exchange" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m timeout=5s container-test-run-dm-wireguard-star> controller # [137022.885765] controller data-mesher[229]: time=2026-10-17T23:27:07.857Z level=INFO msg="merging remote state" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m container-test-run-dm-wireguard-star> controller # [137022.885765] controller data-mesher[229]: time=2026-10-17T23:27:07.857Z level=INFO msg="state exchange complete" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m timeout=5s container-test-run-dm-wireguard-star> controller # [137022.885977] controller data-mesher[229]: time=2026-10-17T23:27:07.857Z level=INFO msg="received file request" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> controller # [137022.886487] controller data-mesher[229]: time=2026-10-17T23:27:07.857Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [137022.886487] controller data-mesher[229]: time=2026-10-17T23:27:07.858Z level=INFO msg="received file request" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> controller # [137022.889244] controller data-mesher[229]: time=2026-10-17T23:27:07.861Z level=INFO msg="file transfer complete" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> controller # [137022.889244] controller data-mesher[229]: time=2026-10-17T23:27:07.861Z level=INFO msg="file transfer complete" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer1 # [137022.871057] peer1 data-mesher[219]: time=2026-10-17T23:27:07.842Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [137022.871057] peer1 data-mesher[219]: time=2026-10-17T23:27:07.842Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137022.872894] peer1 data-mesher[219]: time=2026-10-17T23:27:07.844Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137022.873484] peer1 data-mesher[219]: time=2026-10-17T23:27:07.845Z level=INFO msg="state exchange complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137022.873563] peer1 data-mesher[219]: time=2026-10-17T23:27:07.845Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer2 # [137022.884495] peer2 data-mesher[216]: time=2026-10-17T23:27:07.856Z level=INFO msg="received state sync from peer" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer2 # [137022.884495] peer2 data-mesher[216]: time=2026-10-17T23:27:07.856Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer2 # [137022.885328] peer2 data-mesher[216]: time=2026-10-17T23:27:07.856Z level=DEBUG msg="new file detected" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> peer2 # [137022.885328] peer2 data-mesher[216]: time=2026-10-17T23:27:07.857Z level=DEBUG msg="new file detected" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer2 # [137022.885328] peer2 data-mesher[216]: time=2026-10-17T23:27:07.857Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k container-test-run-dm-wireguard-star> peer2 # [137022.885328] peer2 data-mesher[216]: time=2026-10-17T23:27:07.857Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller container-test-run-dm-wireguard-star> peer2 # [137022.885526] peer2 data-mesher[216]: time=2026-10-17T23:27:07.857Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-10-17 23:26:47.909 +0000 UTC" signed_by="vc56aLNWYkmsIoih9geuAdIW+euKM0kLd62fxmFBpJE=" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer2 # [137022.885526] peer2 data-mesher[216]: time=2026-10-17T23:27:07.857Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k signed_at="2026-10-17 23:26:47.992 +0000 UTC" signed_by="TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM/xJi3k=" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer2 # [137022.891816] peer2 data-mesher[216]: time=2026-10-17T23:27:07.863Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-10-17 23:26:47.909 +0000 UTC" signed_by="vc56aLNWYkmsIoih9geuAdIW+euKM0kLd62fxmFBpJE=" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk written=true elapsed=6.434728ms container-test-run-dm-wireguard-star> peer2 # [137022.894135] peer2 data-mesher[216]: time=2026-10-17T23:27:07.865Z level=INFO msg="download complete" name=dm_wg_star_wg_star/TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM_xJi3k signed_at="2026-10-17 23:26:47.992 +0000 UTC" signed_by="TgH6Uxy06GHAZnZZWIzHaNV0jGNbqTVzfk9VM/xJi3k=" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk written=true elapsed=8.358994ms container-test-run-dm-wireguard-star> peer2 # [137022.894392] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer2 # [137023.060910] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [137023.061253] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state... container-test-run-dm-wireguard-star> peer2 # [137026.461025] peer2 data-mesher[216]: time=2026-10-17T23:27:11.432Z level=INFO msg="performing state exchange with peers on join" count=1 container-test-run-dm-wireguard-star> peer2 # [137026.461025] peer2 data-mesher[216]: time=2026-10-17T23:27:11.432Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer2 # [137026.463007] peer2 data-mesher[216]: time=2026-10-17T23:27:11.434Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer2 # [137026.463618] peer2 data-mesher[216]: time=2026-10-17T23:27:11.435Z level=INFO msg="state exchange complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer2 # [137026.463698] peer2 data-mesher[216]: time=2026-10-17T23:27:11.435Z level=INFO msg="server started" container-test-run-dm-wireguard-star> peer2 # [137026.463908] peer2 data-mesher[216]: time=2026-10-17T23:27:11.435Z level=INFO msg="starting expired-file sweeper" interval=1m0s container-test-run-dm-wireguard-star> peer2 # [137026.464198] peer2 systemd[1]: Started data mesher daemon. container-test-run-dm-wireguard-star> peer2 # [137026.466343] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher... container-test-run-dm-wireguard-star> peer2 # [137026.633719] peer2 data-mesher[216]: time=2026-10-17T23:27:11.605Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 status=204 container-test-run-dm-wireguard-star> peer2 # [137026.633949] peer2 dm-wg-star-publish[294]: Status: 204 No Content container-test-run-dm-wireguard-star> peer2 # [137026.636224] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher... container-test-run-dm-wireguard-star> peer2 # [137026.638225] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [137026.638543] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher. container-test-run-dm-wireguard-star> peer2 # [137026.639578] peer2 systemd[1]: Reached target Multi-User System. container-test-run-dm-wireguard-star> controller # [137026.461886] controller data-mesher[229]: time=2026-10-17T23:27:11.433Z level=INFO msg="received state sync from peer" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m container-test-run-dm-wireguard-star> controller # [137026.461886] controller data-mesher[229]: time=2026-10-17T23:27:11.433Z level=INFO msg="merging remote state" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m container-test-run-dm-wireguard-star> peer2 # [137026.784347] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> peer2 # [137026.784671] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher. container-test-run-dm-wireguard-star> peer2 # [137026.785210] peer2 systemd[1]: Startup finished in 11.741s. container-test-run-dm-wireguard-star> peer2: (finished: waiting for unit data-mesher.service, in 12.67 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/8xlk88ia8b5yg9kfrg3skndwzgphipvr-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/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39 container-test-run-dm-wireguard-star> controller # [137027.874438] controller data-mesher[229]: time=2026-10-17T23:27:12.846Z level=INFO msg="received state sync from peer" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137027.874438] controller data-mesher[229]: time=2026-10-17T23:27:12.846Z level=INFO msg="merging remote state" peer=12D3KooWF4sid5maaETzEuSUkMWxQtgSLYMgWHtmmDuvzD99BP4c container-test-run-dm-wireguard-star> controller # [137027.887182] controller data-mesher[229]: time=2026-10-17T23:27:12.859Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> controller # [137027.887290] controller data-mesher[229]: time=2026-10-17T23:27:12.859Z level=DEBUG msg="initiating state exchange" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m timeout=5s container-test-run-dm-wireguard-star> controller # [137027.888939] controller data-mesher[229]: time=2026-10-17T23:27:12.860Z level=INFO msg="merging remote state" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m container-test-run-dm-wireguard-star> controller # [137027.889552] controller data-mesher[229]: time=2026-10-17T23:27:12.861Z level=DEBUG msg="new file detected" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m name=dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 name=dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 container-test-run-dm-wireguard-star> controller # [137027.889721] controller data-mesher[229]: time=2026-10-17T23:27:12.861Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 container-test-run-dm-wireguard-star> controller # [137027.889848] controller data-mesher[229]: time=2026-10-17T23:27:12.861Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 signed_at="2026-10-17 23:27:11.601 +0000 UTC" signed_by="MfaiOwfdzb+LFVSD0fRH+R6M3gnlsuvhz5OELcL7Iv4=" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m container-test-run-dm-wireguard-star> controller # [137027.890189] controller data-mesher[229]: time=2026-10-17T23:27:12.861Z level=INFO msg="state exchange complete" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m timeout=5s container-test-run-dm-wireguard-star> controller # [137027.890189] controller data-mesher[229]: time=2026-10-17T23:27:12.862Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> controller # [137027.894968] controller data-mesher[229]: time=2026-10-17T23:27:12.866Z level=INFO msg="download complete" name=dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 signed_at="2026-10-17 23:27:11.601 +0000 UTC" signed_by="MfaiOwfdzb+LFVSD0fRH+R6M3gnlsuvhz5OELcL7Iv4=" peer=12D3KooWDBQGctkvPPB3VYspoSj7QbVBnG1T3uUwhGqjRhpS9k8m written=true elapsed=5.192911ms container-test-run-dm-wireguard-star> controller # [137027.897882] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher... container-test-run-dm-wireguard-star> controller # [137028.055258] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully. container-test-run-dm-wireguard-star> controller # [137028.055603] 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::b202:e78a:7743:0ac5 container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::b202:e78a:7743:0ac5, in 0.01 seconds) container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eb76:9549:900d:8944 container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eb76:9549:900d:8944, in 0.01 seconds) container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eb76:9549:900d:8944 container-test-run-dm-wireguard-star> peer1 # [137027.873750] peer1 data-mesher[219]: time=2026-10-17T23:27:12.845Z level=DEBUG msg="attempting push/pull" peer_count=1 container-test-run-dm-wireguard-star> peer1 # [137027.874576] peer1 data-mesher[219]: time=2026-10-17T23:27:12.845Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137027.875583] peer1 data-mesher[219]: time=2026-10-17T23:27:12.847Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer1 # [137027.876225] peer1 data-mesher[219]: time=2026-10-17T23:27:12.848Z level=INFO msg="state exchange complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk timeout=5s container-test-run-dm-wireguard-star> peer1 # [137027.876326] peer1 data-mesher[219]: time=2026-10-17T23:27:12.848Z level=DEBUG msg="push/pull successful" interval=5s container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::eb76:9549:900d:8944, in 0.01 seconds) container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::4ba5:b0d0:89d4:774b container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::4ba5:b0d0:89d4:774b, in 0.01 seconds) container-test-run-dm-wireguard-star> (finished: run the VM test script, in 37.61 seconds) container-test-run-dm-wireguard-star> test script finished in 37.64s container-test-run-dm-wireguard-star> cleanup container-test-run-dm-wireguard-star> kill NspawnMachine (pid 50) container-test-run-dm-wireguard-star> peer2 # [137027.887863] peer2 data-mesher[216]: time=2026-10-17T23:27:12.859Z level=INFO msg="received state sync from peer" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer2 # [137027.887863] peer2 data-mesher[216]: time=2026-10-17T23:27:12.859Z level=INFO msg="merging remote state" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk container-test-run-dm-wireguard-star> peer2 # [137027.890276] peer2 data-mesher[216]: time=2026-10-17T23:27:12.862Z level=INFO msg="received file request" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 container-test-run-dm-wireguard-star> peer2 # [137027.892802] peer2 data-mesher[216]: time=2026-10-17T23:27:12.864Z level=INFO msg="file transfer complete" peer=12D3KooWAFieTHMHTAB9p9ag6LCWr4C2kimJv88B9hHj1HEePtZk network="pI3i8tlhi0bx2FmxJgwaW+6zM6MLcaJzbif7Re+xE+0=" name=dm_wg_star_wg_star/MfaiOwfdzb-LFVSD0fRH-R6M3gnlsuvhz5OELcL7Iv4 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 784) 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)