these 74 derivations will be built:
  /nix/store/6k6ww9fszcbvwlx9ldq8bk1hy9zqhi3v-dm.toml.drv
  /nix/store/s40wid4bjr1sfmmqw092gybjyg0axbyl-X-Restart-Triggers-data-mesher.drv
  /nix/store/0hr9dclv7vamp6zc44qzxvrvz99dhpdf-unit-data-mesher.service.drv
  /nix/store/105f3m1g5shkxpz28z8720mqhmfz25l2-unit-40-wg-star.netdev.drv
  /nix/store/1fw0h0r9gahflkqbnizvkd7xxgki9zqb-X-Restart-Triggers-systemd-networkd.drv
  /nix/store/6zrgh723ml8k29bgy3bl2r9jlig5wg2h-unit-40-wg-star.network.drv
  /nix/store/4sh2nrifkn28accjy1i4f738w8ylpf2m-X-Reload-Triggers-systemd-networkd.drv
  /nix/store/0incal3zgck7970bjg22qlvnvc6gw8hr-unit-systemd-networkd.service.drv
  /nix/store/k8bpmnfw3b9ay2yk3j1mh24mwas1g03w-extra-hosts.drv
  /nix/store/17knxjmiz74spr07qi9s4pbnwvx06lnb-hosts.drv
  /nix/store/5mdlfq3hxarnnak7ad0n8i1gxipsjzlc-hosts.drv
  /nix/store/6fic3pbc3mlsx8apwfg773jw6f0kxb1f-unit-40-wg-star.netdev.drv
  /nix/store/98309khdmlzgzgqibbbwz1i9phzp7gmg-vars-check-dm-wireguard-star.drv
  /nix/store/9nivinzm32spydjxcd5rfmq2bw8faaci-system-path.drv
  /nix/store/dik959c5xmxlj8jgs51pdsx0nxph1hq0-dbus-1.drv
  /nix/store/473qxl2iqpwyjhhwhl0bac27a1qgrm6d-X-Restart-Triggers-dbus-broker.drv
  /nix/store/j72bxr17zv6jl2y4qzwbkspzxb7hwhik-unit-dbus-broker.service.drv
  /nix/store/pa1rr3ab662wzhpy05inplj72d9vf26l-user-units.drv
  /nix/store/21s3lgrmips884byndigfyg5xrjj945w-unit-dm-wg-star-wg-star-reconfig.path.drv
  /nix/store/x5y9pkqrsrivxx68z55jnn405naqj212-unit-40-wg-star.network.drv
  /nix/store/ibw6c835z3jf71bxslahs48clall0qd9-X-Reload-Triggers-systemd-networkd.drv
  /nix/store/v3dnin6nj73c8w8n2v1q29g7dphq8zj8-X-Restart-Triggers-systemd-networkd.drv
  /nix/store/7j446bbbn3qlkhrcm2s10c8l60lfig7p-unit-systemd-networkd.service.drv
  /nix/store/l5jfaf302ngz5y4m984ybq4q3cnkfw4l-unit-script-dm-wg-star-wg-star-publish-start.drv
  /nix/store/8djy31cfsjyhdvfwkwsbcz9mbb78zbnl-unit-dm-wg-star-wg-star-publish.service.drv
  /nix/store/i1dnkkd6x3prfpsqgjbrf62y1aphax36-unit-dbus-broker.service.drv
  /nix/store/av965msh86lfs2l6gr7wr5qrldklxyv6-firewall-start.drv
  /nix/store/gnmk6b3sqy1sdzy11ym42bs6gqbfy8kf-firewall-reload.drv
  /nix/store/i7qqgm3y39pwggfk99ggnj630ggfhrcr-unit-firewall.service.drv
  /nix/store/isk7baxvmhw2mz83xz0inp54b093q7nd-unit-script-dm-wg-star-wg-star-reconfig-start.drv
  /nix/store/vih1bp9nkg99jqh6730x7np851156r3h-unit-dm-wg-star-wg-star-reconfig.service.drv
  /nix/store/s7gn9f65dcarg6f3glqyx6kdwsdwb99p-system-units.drv
  /nix/store/1l9kz05r5z9wl3y53ig82rz75zjz86vk-etc.drv
  /nix/store/zzvlkwckizm420bk5s505z17i0lafp2a-decrypt-age-secrets.drv
  /nix/store/28ky7hn1bp88w7pnl6b1823xdqs8c9r1-dry-activate.drv
  /nix/store/5j2zacdfr5lkd3vzzibvcys7kxy468a3-dm.toml.drv
  /nix/store/9nkpf5hm3m87yhdrc6iv9jbd82ps9gic-unit-dm-wg-star-wg-star-publish.timer.drv
  /nix/store/4gxjhasx1gvd18jfcaiv6pspnnm8zfx6-unit-script-dm-wg-star-wg-star-reconfig-start.drv
  /nix/store/bdj33yz8rsg6i82zr4lycj6xwicwkwlv-unit-dm-wg-star-wg-star-reconfig.service.drv
  /nix/store/nfyj04r1dcirsahixbymjipmaankafpa-unit-script-dm-wg-star-wg-star-publish-start.drv
  /nix/store/ih32wj2nvcyj854ikjkf1s5m8d92gcn2-unit-dm-wg-star-wg-star-publish.service.drv
  /nix/store/3ijp1j0whr3fm8knx4n0pyff32an04yd-X-Restart-Triggers-data-mesher.drv
  /nix/store/q98dp68b84k9g077p23ikcpzz2jn6afk-unit-data-mesher.service.drv
  /nix/store/zzf5s9rq5z3xic2896fxl9ipfqrk57hy-unit-dm-wg-star-wg-star-reconfig.path.drv
  /nix/store/r6977l5qpcwaga3krzq64lvmr9ggnjpp-system-units.drv
  /nix/store/3hvnn72qsr1airc9r6l0az1kj8asb2rm-etc.drv
  /nix/store/49fa6zw7dq8x1x260f8hjgac9rrpm7lq-test-script.drv
  /nix/store/k20jqyhh8nknpk01w87bg3a3ynyckc4v-unit-40-wg-star.network.drv
  /nix/store/j7m9sq98jfmr8d64c8jx2m4hpv1qgkpz-X-Reload-Triggers-systemd-networkd.drv
  /nix/store/k674c60z6w4ysircsiv7l3nmfy1jgczy-unit-systemd-networkd.service.drv
  /nix/store/99qsdna4falxfp0pqwq1n2g8w1gjnyi3-dm.toml.drv
  /nix/store/g5h289lp6sxbsqinwk0yfx1n59d4k7fk-X-Restart-Triggers-data-mesher.drv
  /nix/store/kswi1z6lwnvrvh1dh5la6kgx1bmi7axj-unit-data-mesher.service.drv
  /nix/store/6nv1pfv0s7y27xk11phmm8igb2cxbh14-unit-script-dm-wg-star-wg-star-publish-start.drv
  /nix/store/qaijsjx8xpmv2sjkqr0x0873fsrbxkqw-unit-dm-wg-star-wg-star-publish.service.drv
  /nix/store/63j4bsa90i8b19bk5r54a3vl1mh2sypr-system-units.drv
  /nix/store/dqdjw39car0bvwi4cs5xq5k787xi2map-hosts.drv
  /nix/store/76rxwbv0slsn6g8zsyqj9qk8sw1mfp09-etc.drv
  /nix/store/73bcmnzigvwqw89hw1yrzy443n9q430d-activate.drv
  /nix/store/sprk798766kwxybab6bkp9vs6g1q2pcd-decrypt-age-secrets.drv
  /nix/store/7kzqzf920llw2vgfizjj598prgkvlk84-dry-activate.drv
  /nix/store/vjffmhb6467g689jf4l5bwskxbgk6mlp-decrypt-age-secrets.drv
  /nix/store/wx66n8mfhcnqkd64zijdbss8gcmbkyl8-activate.drv
  /nix/store/xdxrz0j8zlhv7c6p41bjhqsgr39n9syv-dry-activate.drv
  /nix/store/jschcxhw7nhks2hqd765z9wfjgakm3i9-nixos-system-peer1-test.drv
  /nix/store/9yl0cqhywrvyfpxnbhhz01qibmqwpwnr-run-peer1-nspawn.drv
  /nix/store/vw5r9rms5v06zn5xw3k3yzg80psi3s27-nixos-system-peer2-test.drv
  /nix/store/fpkyy65b5ryw3k2s7q3n8fmffax54z5c-run-peer2-nspawn.drv
  /nix/store/vg6l33mf8iw8m9nb5y0rh8cy8yvikipm-activate.drv
  /nix/store/sa9nz8wwngq2zfmlmwj029p9rsnlq5b1-nixos-system-controller-test.drv
  /nix/store/l9dq52xlpnc467pmrh6hhv5x7mrl96in-run-controller-nspawn.drv
  /nix/store/dlp909janhxiy36v1xgqdlp0m5jyfgdl-driverConfiguration.json.drv
  /nix/store/92bqd4s04h0v3vw5fj30h7bn5brr3mkw-nixos-test-driver-dm-wireguard-star.drv
  /nix/store/bc326filxyx2sp4xjghqr5zvxwv42qvl-container-test-run-dm-wireguard-star.drv
building '/nix/store/49fa6zw7dq8x1x260f8hjgac9rrpm7lq-test-script.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/9nivinzm32spydjxcd5rfmq2bw8faaci-system-path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/105f3m1g5shkxpz28z8720mqhmfz25l2-unit-40-wg-star.netdev.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/6fic3pbc3mlsx8apwfg773jw6f0kxb1f-unit-40-wg-star.netdev.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/6zrgh723ml8k29bgy3bl2r9jlig5wg2h-unit-40-wg-star.network.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/k20jqyhh8nknpk01w87bg3a3ynyckc4v-unit-40-wg-star.network.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/x5y9pkqrsrivxx68z55jnn405naqj212-unit-40-wg-star.network.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/k8bpmnfw3b9ay2yk3j1mh24mwas1g03w-extra-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/9nkpf5hm3m87yhdrc6iv9jbd82ps9gic-unit-dm-wg-star-wg-star-publish.timer.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/21s3lgrmips884byndigfyg5xrjj945w-unit-dm-wg-star-wg-star-reconfig.path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/zzf5s9rq5z3xic2896fxl9ipfqrk57hy-unit-dm-wg-star-wg-star-reconfig.path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/av965msh86lfs2l6gr7wr5qrldklxyv6-firewall-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/6nv1pfv0s7y27xk11phmm8igb2cxbh14-unit-script-dm-wg-star-wg-star-publish-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/l5jfaf302ngz5y4m984ybq4q3cnkfw4l-unit-script-dm-wg-star-wg-star-publish-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nfyj04r1dcirsahixbymjipmaankafpa-unit-script-dm-wg-star-wg-star-publish-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/4gxjhasx1gvd18jfcaiv6pspnnm8zfx6-unit-script-dm-wg-star-wg-star-reconfig-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/isk7baxvmhw2mz83xz0inp54b093q7nd-unit-script-dm-wg-star-wg-star-reconfig-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/k8bpmnfw3b9ay2yk3j1mh24mwas1g03w-extra-hosts.drv'
extra-hosts> structuredAttrs is enabled
building '/nix/store/av965msh86lfs2l6gr7wr5qrldklxyv6-firewall-start.drv'
firewall-start> structuredAttrs is enabled
building '/nix/store/49fa6zw7dq8x1x260f8hjgac9rrpm7lq-test-script.drv'
test-script> structuredAttrs is enabled
building '/nix/store/105f3m1g5shkxpz28z8720mqhmfz25l2-unit-40-wg-star.netdev.drv'
unit-40-wg-star.netdev> structuredAttrs is enabled
building '/nix/store/6fic3pbc3mlsx8apwfg773jw6f0kxb1f-unit-40-wg-star.netdev.drv'
unit-40-wg-star.netdev> structuredAttrs is enabled
building '/nix/store/6zrgh723ml8k29bgy3bl2r9jlig5wg2h-unit-40-wg-star.network.drv'
unit-40-wg-star.network> structuredAttrs is enabled
building '/nix/store/k20jqyhh8nknpk01w87bg3a3ynyckc4v-unit-40-wg-star.network.drv'
unit-40-wg-star.network> structuredAttrs is enabled
building '/nix/store/x5y9pkqrsrivxx68z55jnn405naqj212-unit-40-wg-star.network.drv'
unit-40-wg-star.network> structuredAttrs is enabled
building '/nix/store/9nkpf5hm3m87yhdrc6iv9jbd82ps9gic-unit-dm-wg-star-wg-star-publish.timer.drv'
unit-dm-wg-star-wg-star-publish.timer> structuredAttrs is enabled
building '/nix/store/21s3lgrmips884byndigfyg5xrjj945w-unit-dm-wg-star-wg-star-reconfig.path.drv'
unit-dm-wg-star-wg-star-reconfig.path> structuredAttrs is enabled
building '/nix/store/zzf5s9rq5z3xic2896fxl9ipfqrk57hy-unit-dm-wg-star-wg-star-reconfig.path.drv'
unit-dm-wg-star-wg-star-reconfig.path> structuredAttrs is enabled
building '/nix/store/l5jfaf302ngz5y4m984ybq4q3cnkfw4l-unit-script-dm-wg-star-wg-star-publish-start.drv'
unit-script-dm-wg-star-wg-star-publish-start> structuredAttrs is enabled
building '/nix/store/4sh2nrifkn28accjy1i4f738w8ylpf2m-X-Reload-Triggers-systemd-networkd.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/ibw6c835z3jf71bxslahs48clall0qd9-X-Reload-Triggers-systemd-networkd.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/j7m9sq98jfmr8d64c8jx2m4hpv1qgkpz-X-Reload-Triggers-systemd-networkd.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/1fw0h0r9gahflkqbnizvkd7xxgki9zqb-X-Restart-Triggers-systemd-networkd.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/v3dnin6nj73c8w8n2v1q29g7dphq8zj8-X-Restart-Triggers-systemd-networkd.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/17knxjmiz74spr07qi9s4pbnwvx06lnb-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/5mdlfq3hxarnnak7ad0n8i1gxipsjzlc-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/dqdjw39car0bvwi4cs5xq5k787xi2map-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/5j2zacdfr5lkd3vzzibvcys7kxy468a3-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/6k6ww9fszcbvwlx9ldq8bk1hy9zqhi3v-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/99qsdna4falxfp0pqwq1n2g8w1gjnyi3-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/98309khdmlzgzgqibbbwz1i9phzp7gmg-vars-check-dm-wireguard-star.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nfyj04r1dcirsahixbymjipmaankafpa-unit-script-dm-wg-star-wg-star-publish-start.drv'
unit-script-dm-wg-star-wg-star-publish-start> structuredAttrs is enabled
building '/nix/store/4gxjhasx1gvd18jfcaiv6pspnnm8zfx6-unit-script-dm-wg-star-wg-star-reconfig-start.drv'
unit-script-dm-wg-star-wg-star-reconfig-start> structuredAttrs is enabled
building '/nix/store/gnmk6b3sqy1sdzy11ym42bs6gqbfy8kf-firewall-reload.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/8djy31cfsjyhdvfwkwsbcz9mbb78zbnl-unit-dm-wg-star-wg-star-publish.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/1fw0h0r9gahflkqbnizvkd7xxgki9zqb-X-Restart-Triggers-systemd-networkd.drv'
building '/nix/store/v3dnin6nj73c8w8n2v1q29g7dphq8zj8-X-Restart-Triggers-systemd-networkd.drv'
building '/nix/store/6nv1pfv0s7y27xk11phmm8igb2cxbh14-unit-script-dm-wg-star-wg-star-publish-start.drv'
unit-script-dm-wg-star-wg-star-publish-start> structuredAttrs is enabled
building '/nix/store/isk7baxvmhw2mz83xz0inp54b093q7nd-unit-script-dm-wg-star-wg-star-reconfig-start.drv'
unit-script-dm-wg-star-wg-star-reconfig-start> structuredAttrs is enabled
building '/nix/store/4sh2nrifkn28accjy1i4f738w8ylpf2m-X-Reload-Triggers-systemd-networkd.drv'
X-Reload-Triggers-systemd-networkd> structuredAttrs is enabled
building '/nix/store/ibw6c835z3jf71bxslahs48clall0qd9-X-Reload-Triggers-systemd-networkd.drv'
X-Reload-Triggers-systemd-networkd> structuredAttrs is enabled
building '/nix/store/j7m9sq98jfmr8d64c8jx2m4hpv1qgkpz-X-Reload-Triggers-systemd-networkd.drv'
X-Reload-Triggers-systemd-networkd> structuredAttrs is enabled
X-Restart-Triggers-systemd-networkd> structuredAttrs is enabled
X-Restart-Triggers-systemd-networkd> structuredAttrs is enabled
building '/nix/store/5j2zacdfr5lkd3vzzibvcys7kxy468a3-dm.toml.drv'
dm.toml> structuredAttrs is enabled
building '/nix/store/6k6ww9fszcbvwlx9ldq8bk1hy9zqhi3v-dm.toml.drv'
building '/nix/store/99qsdna4falxfp0pqwq1n2g8w1gjnyi3-dm.toml.drv'
building '/nix/store/gnmk6b3sqy1sdzy11ym42bs6gqbfy8kf-firewall-reload.drv'
building '/nix/store/17knxjmiz74spr07qi9s4pbnwvx06lnb-hosts.drv'
building '/nix/store/5mdlfq3hxarnnak7ad0n8i1gxipsjzlc-hosts.drv'
building '/nix/store/dqdjw39car0bvwi4cs5xq5k787xi2map-hosts.drv'
dm.toml> structuredAttrs is enabled
dm.toml> structuredAttrs is enabled
firewall-reload> structuredAttrs is enabled
building '/nix/store/ih32wj2nvcyj854ikjkf1s5m8d92gcn2-unit-dm-wg-star-wg-star-publish.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/bdj33yz8rsg6i82zr4lycj6xwicwkwlv-unit-dm-wg-star-wg-star-reconfig.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/sprk798766kwxybab6bkp9vs6g1q2pcd-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vjffmhb6467g689jf4l5bwskxbgk6mlp-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/zzvlkwckizm420bk5s505z17i0lafp2a-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3ijp1j0whr3fm8knx4n0pyff32an04yd-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/s40wid4bjr1sfmmqw092gybjyg0axbyl-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/qaijsjx8xpmv2sjkqr0x0873fsrbxkqw-unit-dm-wg-star-wg-star-publish.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vih1bp9nkg99jqh6730x7np851156r3h-unit-dm-wg-star-wg-star-reconfig.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0incal3zgck7970bjg22qlvnvc6gw8hr-unit-systemd-networkd.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/7j446bbbn3qlkhrcm2s10c8l60lfig7p-unit-systemd-networkd.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/k674c60z6w4ysircsiv7l3nmfy1jgczy-unit-systemd-networkd.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/98309khdmlzgzgqibbbwz1i9phzp7gmg-vars-check-dm-wireguard-star.drv'
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/g5h289lp6sxbsqinwk0yfx1n59d4k7fk-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/8djy31cfsjyhdvfwkwsbcz9mbb78zbnl-unit-dm-wg-star-wg-star-publish.service.drv'
unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled
building '/nix/store/ih32wj2nvcyj854ikjkf1s5m8d92gcn2-unit-dm-wg-star-wg-star-publish.service.drv'
unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled
building '/nix/store/i7qqgm3y39pwggfk99ggnj630ggfhrcr-unit-firewall.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/sprk798766kwxybab6bkp9vs6g1q2pcd-decrypt-age-secrets.drv'
decrypt-age-secrets> structuredAttrs is enabled
building '/nix/store/vjffmhb6467g689jf4l5bwskxbgk6mlp-decrypt-age-secrets.drv'
building '/nix/store/zzvlkwckizm420bk5s505z17i0lafp2a-decrypt-age-secrets.drv'
building '/nix/store/bdj33yz8rsg6i82zr4lycj6xwicwkwlv-unit-dm-wg-star-wg-star-reconfig.service.drv'
unit-dm-wg-star-wg-star-reconfig.service> structuredAttrs is enabled
decrypt-age-secrets> structuredAttrs is enabled
decrypt-age-secrets> structuredAttrs is enabled
building '/nix/store/7kzqzf920llw2vgfizjj598prgkvlk84-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/xdxrz0j8zlhv7c6p41bjhqsgr39n9syv-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/28ky7hn1bp88w7pnl6b1823xdqs8c9r1-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/9nivinzm32spydjxcd5rfmq2bw8faaci-system-path.drv'
system-path> structuredAttrs is enabled
system-path> created 1726 symlinks in user environment
building '/nix/store/s40wid4bjr1sfmmqw092gybjyg0axbyl-X-Restart-Triggers-data-mesher.drv'
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
building '/nix/store/i7qqgm3y39pwggfk99ggnj630ggfhrcr-unit-firewall.service.drv'
unit-firewall.service> structuredAttrs is enabled
building '/nix/store/dik959c5xmxlj8jgs51pdsx0nxph1hq0-dbus-1.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/g5h289lp6sxbsqinwk0yfx1n59d4k7fk-X-Restart-Triggers-data-mesher.drv'
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
building '/nix/store/28ky7hn1bp88w7pnl6b1823xdqs8c9r1-dry-activate.drv'
dry-activate> structuredAttrs is enabled
building '/nix/store/0incal3zgck7970bjg22qlvnvc6gw8hr-unit-systemd-networkd.service.drv'
unit-systemd-networkd.service> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/3ijp1j0whr3fm8knx4n0pyff32an04yd-X-Restart-Triggers-data-mesher.drv'
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/0hr9dclv7vamp6zc44qzxvrvz99dhpdf-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/k674c60z6w4ysircsiv7l3nmfy1jgczy-unit-systemd-networkd.service.drv'
unit-systemd-networkd.service> structuredAttrs is enabled
building '/nix/store/kswi1z6lwnvrvh1dh5la6kgx1bmi7axj-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/q98dp68b84k9g077p23ikcpzz2jn6afk-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/qaijsjx8xpmv2sjkqr0x0873fsrbxkqw-unit-dm-wg-star-wg-star-publish.service.drv'
unit-dm-wg-star-wg-star-publish.service> structuredAttrs is enabled
building '/nix/store/7j446bbbn3qlkhrcm2s10c8l60lfig7p-unit-systemd-networkd.service.drv'
unit-systemd-networkd.service> structuredAttrs is enabled
building '/nix/store/xdxrz0j8zlhv7c6p41bjhqsgr39n9syv-dry-activate.drv'
dry-activate> structuredAttrs is enabled
building '/nix/store/7kzqzf920llw2vgfizjj598prgkvlk84-dry-activate.drv'
dry-activate> structuredAttrs is enabled
building '/nix/store/vih1bp9nkg99jqh6730x7np851156r3h-unit-dm-wg-star-wg-star-reconfig.service.drv'
unit-dm-wg-star-wg-star-reconfig.service> structuredAttrs is enabled
building '/nix/store/dik959c5xmxlj8jgs51pdsx0nxph1hq0-dbus-1.drv'
building '/nix/store/473qxl2iqpwyjhhwhl0bac27a1qgrm6d-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0hr9dclv7vamp6zc44qzxvrvz99dhpdf-unit-data-mesher.service.drv'
unit-data-mesher.service> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/kswi1z6lwnvrvh1dh5la6kgx1bmi7axj-unit-data-mesher.service.drv'
unit-data-mesher.service> structuredAttrs is enabled
building '/nix/store/q98dp68b84k9g077p23ikcpzz2jn6afk-unit-data-mesher.service.drv'
unit-data-mesher.service> structuredAttrs is enabled
building '/nix/store/473qxl2iqpwyjhhwhl0bac27a1qgrm6d-X-Restart-Triggers-dbus-broker.drv'
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
building '/nix/store/i1dnkkd6x3prfpsqgjbrf62y1aphax36-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/j72bxr17zv6jl2y4qzwbkspzxb7hwhik-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/i1dnkkd6x3prfpsqgjbrf62y1aphax36-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/j72bxr17zv6jl2y4qzwbkspzxb7hwhik-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/63j4bsa90i8b19bk5r54a3vl1mh2sypr-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/r6977l5qpcwaga3krzq64lvmr9ggnjpp-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/s7gn9f65dcarg6f3glqyx6kdwsdwb99p-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/pa1rr3ab662wzhpy05inplj72d9vf26l-user-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/r6977l5qpcwaga3krzq64lvmr9ggnjpp-system-units.drv'
building '/nix/store/s7gn9f65dcarg6f3glqyx6kdwsdwb99p-system-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/63j4bsa90i8b19bk5r54a3vl1mh2sypr-system-units.drv'
building '/nix/store/pa1rr3ab662wzhpy05inplj72d9vf26l-user-units.drv'
building '/nix/store/1l9kz05r5z9wl3y53ig82rz75zjz86vk-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3hvnn72qsr1airc9r6l0az1kj8asb2rm-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/1l9kz05r5z9wl3y53ig82rz75zjz86vk-etc.drv'
building '/nix/store/76rxwbv0slsn6g8zsyqj9qk8sw1mfp09-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3hvnn72qsr1airc9r6l0az1kj8asb2rm-etc.drv'
building '/nix/store/wx66n8mfhcnqkd64zijdbss8gcmbkyl8-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/76rxwbv0slsn6g8zsyqj9qk8sw1mfp09-etc.drv'
building '/nix/store/vg6l33mf8iw8m9nb5y0rh8cy8yvikipm-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/73bcmnzigvwqw89hw1yrzy443n9q430d-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/wx66n8mfhcnqkd64zijdbss8gcmbkyl8-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/vg6l33mf8iw8m9nb5y0rh8cy8yvikipm-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/sa9nz8wwngq2zfmlmwj029p9rsnlq5b1-nixos-system-controller-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jschcxhw7nhks2hqd765z9wfjgakm3i9-nixos-system-peer1-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/73bcmnzigvwqw89hw1yrzy443n9q430d-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/sa9nz8wwngq2zfmlmwj029p9rsnlq5b1-nixos-system-controller-test.drv'
nixos-system-controller-test> structuredAttrs is enabled
building '/nix/store/vw5r9rms5v06zn5xw3k3yzg80psi3s27-nixos-system-peer2-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/l9dq52xlpnc467pmrh6hhv5x7mrl96in-run-controller-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jschcxhw7nhks2hqd765z9wfjgakm3i9-nixos-system-peer1-test.drv'
nixos-system-peer1-test> structuredAttrs is enabled
building '/nix/store/vw5r9rms5v06zn5xw3k3yzg80psi3s27-nixos-system-peer2-test.drv'
nixos-system-peer2-test> structuredAttrs is enabled
building '/nix/store/fpkyy65b5ryw3k2s7q3n8fmffax54z5c-run-peer2-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/l9dq52xlpnc467pmrh6hhv5x7mrl96in-run-controller-nspawn.drv'
building '/nix/store/9yl0cqhywrvyfpxnbhhz01qibmqwpwnr-run-peer1-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/9yl0cqhywrvyfpxnbhhz01qibmqwpwnr-run-peer1-nspawn.drv'
building '/nix/store/fpkyy65b5ryw3k2s7q3n8fmffax54z5c-run-peer2-nspawn.drv'
building '/nix/store/dlp909janhxiy36v1xgqdlp0m5jyfgdl-driverConfiguration.json.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/dlp909janhxiy36v1xgqdlp0m5jyfgdl-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/92bqd4s04h0v3vw5fj30h7bn5brr3mkw-nixos-test-driver-dm-wireguard-star.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/92bqd4s04h0v3vw5fj30h7bn5brr3mkw-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/bc326filxyx2sp4xjghqr5zvxwv42qvl-container-test-run-dm-wireguard-star.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/bc326filxyx2sp4xjghqr5zvxwv42qvl-container-test-run-dm-wireguard-star.drv'
container-test-run-dm-wireguard-star> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-dm-wireguard-star> start all VLans
container-test-run-dm-wireguard-star> (finished: start all VLans, in 0.00 seconds)
container-test-run-dm-wireguard-star> 
container-test-run-dm-wireguard-star> Test will time out and terminate in 3600.0 seconds
container-test-run-dm-wireguard-star> run the VM test script
container-test-run-dm-wireguard-star> additionally exposed symbols:
container-test-run-dm-wireguard-star>     controller, peer1, peer2,
container-test-run-dm-wireguard-star>     vlan1,
container-test-run-dm-wireguard-star>     start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
container-test-run-dm-wireguard-star> controller: systemd-nspawn running (pid 50)
container-test-run-dm-wireguard-star> controller: Waiting for journal at /build/vm-state-controller/var/log/journal...
container-test-run-dm-wireguard-star> peer1: systemd-nspawn running (pid 53)
container-test-run-dm-wireguard-star> peer1: Waiting for journal at /build/vm-state-peer1/var/log/journal...
container-test-run-dm-wireguard-star> controller: waiting for unit data-mesher.service
container-test-run-dm-wireguard-star> nixos-nspawn(peer1): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-nspawn(peer1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-dm-wireguard-star> nixos-nspawn(controller): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-nspawn(controller): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-dm-wireguard-star> ░ Spawning container peer1 on /build/vm-state-peer1.
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> controller # No journal files were found.
container-test-run-dm-wireguard-star> controller # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> peer1 # No journal files were found.
container-test-run-dm-wireguard-star> peer1 # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> controller # [1564580.642057] controller systemd-journald[108]: Journal started
container-test-run-dm-wireguard-star> controller # [1564580.642117] controller systemd-journald[108]: Runtime Journal (/run/log/journal/dc6e9a95d01740caa1de0c55c6f71df8) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> controller # [1564580.656436] controller systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> controller # [1564580.669536] controller systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> controller # [1564580.684985] controller systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> controller # [1564580.685843] controller systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> controller # [1564580.686704] controller systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> controller # [1564580.735242] controller systemd-journald[108]: Time spent on flushing to /var/log/journal/dc6e9a95d01740caa1de0c55c6f71df8 is 1.447ms for 7 entries.
container-test-run-dm-wireguard-star> controller # [1564580.735242] controller systemd-journald[108]: System Journal (/var/log/journal/dc6e9a95d01740caa1de0c55c6f71df8) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> controller # [1564580.746906] controller systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> controller # [1564580.747674] controller systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> controller # [1564580.747812] controller systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> controller # [1564580.748923] controller systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> controller # [1564580.748986] controller systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [1564580.750049] controller systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> controller # [1564580.750092] controller systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> controller # [1564580.797705] controller systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> controller # [1564580.798858] controller systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> controller # [1564580.815910] controller systemd-tmpfiles[206]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1564580.816117] controller systemd-tmpfiles[206]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1564580.816251] controller systemd-tmpfiles[206]: fchmod() of /var/log/journal/dc6e9a95d01740caa1de0c55c6f71df8 failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1564580.816449] controller systemd-tmpfiles[206]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1564580.817958] controller systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> controller # [1564580.819399] controller systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> controller # [1564580.820279] controller systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> controller # [1564580.827294] controller systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> controller # [1564580.827457] controller systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> controller # [1564580.827664] controller systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> controller # [1564580.828743] controller systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> controller # [1564580.832466] controller systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> controller # [1564580.842099] controller systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> controller # [1564580.843688] controller systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> controller # [1564580.855142] controller systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [1564580.651618] peer1 systemd-journald[99]: Journal started
container-test-run-dm-wireguard-star> peer1 # [1564580.651677] peer1 systemd-journald[99]: Runtime Journal (/run/log/journal/aeb6f38ae53b41008beb76ff1cda7b2b) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> peer1 # [1564580.658001] peer1 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> peer1 # [1564580.670056] peer1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> peer1 # [1564580.684478] peer1 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer1 # [1564580.685332] peer1 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> peer1 # [1564580.686025] peer1 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> peer1 # [1564580.736308] peer1 systemd-journald[99]: Time spent on flushing to /var/log/journal/aeb6f38ae53b41008beb76ff1cda7b2b is 1.152ms for 7 entries.
container-test-run-dm-wireguard-star> peer1 # [1564580.736308] peer1 systemd-journald[99]: System Journal (/var/log/journal/aeb6f38ae53b41008beb76ff1cda7b2b) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer1 # [1564580.746912] peer1 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer1 # [1564580.747602] peer1 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [1564580.747750] peer1 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer1 # [1564580.748908] peer1 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer1 # [1564580.748975] peer1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [1564580.749992] peer1 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer1 # [1564580.750037] peer1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer1 # [1564580.806790] peer1 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer1 # [1564580.807865] peer1 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer1 # [1564580.826799] peer1 systemd-tmpfiles[195]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1564580.827012] peer1 systemd-tmpfiles[195]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1564580.827157] peer1 systemd-tmpfiles[195]: fchmod() of /var/log/journal/aeb6f38ae53b41008beb76ff1cda7b2b failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1564580.827374] peer1 systemd-tmpfiles[195]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1564580.828903] peer1 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer1 # [1564580.830149] peer1 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer1 # [1564580.831001] peer1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer1 # [1564580.833919] peer1 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer1 # [1564580.834059] peer1 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer1 # [1564580.834361] peer1 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer1 # [1564580.835514] peer1 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer1 # [1564580.845918] peer1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer1 # [1564580.854174] peer1 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer1 # [1564580.855119] peer1 systemd[1]: systemd-update-done.service: Failed to spawn executor: No such file or directory
container-test-run-dm-wireguard-star> peer1 # [1564580.855144] peer1 systemd[1]: systemd-update-done.service: Failed to spawn 'start' task: No such file or directory
container-test-run-dm-wireguard-star> peer1 # [1564580.855186] peer1 systemd[1]: systemd-update-done.service: Failed with result 'resources'.
container-test-run-dm-wireguard-star> peer1 # [1564580.855248] peer1 systemd[1]: Failed to start Update is Completed.
container-test-run-dm-wireguard-star> peer1 # [1564581.674373] peer1 systemd-networkd[212]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1564581.674473] peer1 systemd-networkd[212]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer1 # [1564581.685206] peer1 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> peer1 # [1564581.685377] peer1 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> peer1 # [1564581.685544] peer1 systemd-networkd[212]: lo: Link UP
container-test-run-dm-wireguard-star> peer1 # [1564581.685548] peer1 systemd-networkd[212]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1564581.685755] peer1 systemd-networkd[212]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer1 # [1564581.686144] peer1 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer1 # [1564581.686897] peer1 systemd-networkd[212]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer1 # [1564581.687249] peer1 systemd-networkd[212]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer1 # [1564581.687556] peer1 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer1 # [1564581.687626] peer1 systemd-networkd[212]: eth1: Link UP
container-test-run-dm-wireguard-star> peer1 # [1564581.687848] peer1 systemd-networkd[212]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1564581.729037] peer1 systemd-networkd[212]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer1 # [1564581.729042] peer1 systemd-networkd[212]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer1 # [1564581.730129] peer1 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> controller # [1564581.671112] controller systemd-networkd[223]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1564581.671217] controller systemd-networkd[223]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> controller # [1564581.682409] controller systemd-networkd[223]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> controller # [1564581.682580] controller systemd-networkd[223]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-dm-wireguard-star> controller # [1564581.682754] controller systemd-networkd[223]: lo: Link UP
container-test-run-dm-wireguard-star> controller # [1564581.682758] controller systemd-networkd[223]: lo: Gained carrier
container-test-run-dm-wireguard-star> controller # [1564581.682981] controller systemd-networkd[223]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> controller # [1564581.683431] controller systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> controller # [1564581.684257] controller systemd-networkd[223]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> controller # [1564581.684643] controller systemd-networkd[223]: wg-star: netdev ready
container-test-run-dm-wireguard-star> controller # [1564581.685041] controller systemd-networkd[223]: eth1: Link UP
container-test-run-dm-wireguard-star> controller # [1564581.685264] controller systemd-networkd[223]: eth1: Gained carrier
container-test-run-dm-wireguard-star> controller # [1564581.685791] controller systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> controller # [1564581.737025] controller systemd-networkd[223]: wg-star: Link UP
container-test-run-dm-wireguard-star> controller # [1564581.737030] controller systemd-networkd[223]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> controller # [1564581.738255] controller systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer1 # [1564582.141997] peer1 systemd-resolved[133]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer1 # [1564582.142008] peer1 systemd-resolved[133]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer1 # [1564582.142012] peer1 systemd-resolved[133]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer1 # [1564582.142047] peer1 systemd-resolved[133]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
container-test-run-dm-wireguard-star> peer1 # [1564582.164621] peer1 systemd-resolved[133]: Using system hostname 'peer1'.
container-test-run-dm-wireguard-star> peer1 # [1564582.166297] peer1 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer1 # [1564582.166415] peer1 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer1 # [1564582.166488] peer1 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer1 # [1564582.166590] peer1 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1564582.166638] peer1 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer1 # [1564582.166675] peer1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer1 # [1564582.166695] peer1 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer1 # [1564582.166717] peer1 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer1 # [1564582.166749] peer1 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer1 # [1564582.166869] peer1 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer1 # [1564582.166990] peer1 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer1 # [1564582.167098] peer1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer1 # [1564582.167126] peer1 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer1 # [1564582.167166] peer1 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer1 # [1564582.168459] peer1 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer1 # [1564582.170143] peer1 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer1 # [1564582.171197] peer1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer1 # [1564582.172497] peer1 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer1 # [1564582.232979] peer1 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer1 # [1564582.410490] peer1 nsncd[221]: Sep 22 10:03:22.396 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 # [1564582.186448] controller systemd-resolved[146]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> controller # [1564582.186459] controller systemd-resolved[146]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> controller # [1564582.186463] controller systemd-resolved[146]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> controller # [1564582.186499] controller systemd-resolved[146]: 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 # [1564582.209239] controller systemd-resolved[146]: Using system hostname 'controller'.
container-test-run-dm-wireguard-star> controller # [1564582.210914] controller systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> controller # [1564582.211026] controller systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> controller # [1564582.211099] controller systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> controller # [1564582.211191] controller systemd[1]: Started Watch for WireGuard peer changes from data-mesher.
container-test-run-dm-wireguard-star> controller # [1564582.211229] controller systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> controller # [1564582.211265] controller systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> controller # [1564582.211286] controller systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> controller # [1564582.211315] controller systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> controller # [1564582.211458] controller systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> controller # [1564582.211580] controller systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> controller # [1564582.211703] controller systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> controller # [1564582.211730] controller systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> controller # [1564582.211769] controller systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> controller # [1564582.216911] controller systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> controller # [1564582.217919] controller systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> controller # [1564582.219647] controller systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> controller # [1564582.220958] controller systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> controller # [1564582.238372] controller systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> controller # [1564582.428755] controller nsncd[234]: Sep 22 10:03:22.414 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 # [1564582.428822] controller systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> controller # [1564582.428886] controller systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> controller # [1564582.428945] controller systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> controller # [1564582.456397] controller systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer1 # [1564582.410589] peer1 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer1 # [1564582.410670] peer1 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [1564582.410729] peer1 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer1 # [1564582.412036] peer1 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer1 # [1564582.412834] peer1 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer1 # [1564582.462859] peer1 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer1 # [1564582.463979] peer1 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer1 # [1564582.464036] peer1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer1 # [1564582.464061] peer1 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer1 # [1564582.606809] peer1 dbus-broker-launch[222]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer1 # [1564582.608103] peer1 dbus-broker-launch[222]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer1 # [1564582.608103] peer1 dbus-broker-launch[222]: Invalid user-name in /nix/store/d4rlxgnkyjff4mz022znw7p2dm8yg5z3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer1 # [1564582.608834] peer1 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer1 # [1564582.616208] peer1 dbus-broker-launch[222]: Ready
container-test-run-dm-wireguard-star> controller # [1564582.457225] controller systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> controller # [1564582.467612] controller systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> controller # [1564582.468783] controller systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> controller # [1564582.468825] controller systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> controller # [1564582.468850] controller systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> controller # [1564582.614461] controller dbus-broker-launch[235]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> controller # [1564582.615320] controller dbus-broker-launch[235]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> controller # [1564582.615320] controller dbus-broker-launch[235]: Invalid user-name in /nix/store/d4rlxgnkyjff4mz022znw7p2dm8yg5z3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> controller # [1564582.615747] controller systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> controller # [1564582.623054] controller dbus-broker-launch[235]: Ready
container-test-run-dm-wireguard-star> controller # [1564582.852260] controller systemd-networkd[223]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> controller # [1564583.089894] controller data-mesher[232]: time=2026-09-22T10:03:23.075Z 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 # [1564583.091030] controller data-mesher[232]: time=2026-09-22T10:03:23.076Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us: [/dns/controller.clan/tcp/7946]} {12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt: [/dns/peer1.clan/tcp/7946]} {12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> controller # [1564583.091081] controller data-mesher[232]: time=2026-09-22T10:03:23.076Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> controller # [1564583.094353] controller data-mesher[232]: time=2026-09-22T10:03:23.080Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> controller # [1564583.094471] controller data-mesher[232]: time=2026-09-22T10:03:23.080Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> controller # [1564583.098709] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="libp2p host created" peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us 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::294a:40f8:557b:bb3c/tcp/7946]"
container-test-run-dm-wireguard-star> controller # [1564583.099672] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> controller # [1564583.099672] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> controller # [1564583.099672] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> controller # [1564583.099672] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> controller # [1564583.099672] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> controller # [1564583.099672] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> controller # [1564583.099672] controller data-mesher[232]: time=2026-09-22T10:03:23.084Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> controller # [1564583.112893] controller data-mesher[232]: time=2026-09-22T10:03:23.098Z level=INFO msg="peer connected" peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [1564583.072207] peer1 systemd-networkd[212]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer1 # [1564583.096983] peer1 data-mesher[219]: time=2026-09-22T10:03:23.082Z 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 # [1564583.098090] peer1 data-mesher[219]: time=2026-09-22T10:03:23.083Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us: [/dns/controller.clan/tcp/7946]} {12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt: [/dns/peer1.clan/tcp/7946]} {12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer1 # [1564583.098147] peer1 data-mesher[219]: time=2026-09-22T10:03:23.083Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer1 # [1564583.101311] peer1 data-mesher[219]: time=2026-09-22T10:03:23.087Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer1 # [1564583.101433] peer1 data-mesher[219]: time=2026-09-22T10:03:23.087Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer1 # [1564583.106352] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="libp2p host created" peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt 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::8472:4a5:acd7:7705/tcp/7946]"
container-test-run-dm-wireguard-star> peer1 # [1564583.106446] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer1 # [1564583.106446] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [1564583.106446] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer1 # [1564583.106446] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer1 # [1564583.106575] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer1 # [1564583.106608] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer1 # [1564583.106641] peer1 data-mesher[219]: time=2026-09-22T10:03:23.092Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer1 # [1564583.112115] peer1 data-mesher[219]: time=2026-09-22T10:03:23.097Z level=INFO msg="peer connected" peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> controller # [1564583.360785] controller systemd-logind[252]: New seat seat0.
container-test-run-dm-wireguard-star> controller # [1564583.370731] controller systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1564583.373033] controller systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> controller # [1564583.373635] controller systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> controller # [1564583.404688] controller systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> controller # [1564583.414816] controller systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1564583.414962] controller systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> peer1 # [1564583.350365] peer1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1564583.351692] peer1 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer1 # [1564583.369240] peer1 systemd-logind[239]: New seat seat0.
container-test-run-dm-wireguard-star> peer1 # [1564583.369421] peer1 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer1 # [1564583.370815] peer1 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer1 # [1564583.413566] peer1 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1564583.413832] peer1 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller: still waiting for container 'controller' to reach ready state...
container-test-run-dm-wireguard-star> controller # [1564593.099007] controller data-mesher[232]: time=2026-09-22T10:03:33.084Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> controller # [1564593.099764] controller data-mesher[232]: time=2026-09-22T10:03:33.084Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564593.100506] controller data-mesher[232]: time=2026-09-22T10:03:33.086Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564593.100506] controller data-mesher[232]: time=2026-09-22T10:03:33.086Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564593.100646] controller data-mesher[232]: time=2026-09-22T10:03:33.086Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> controller # [1564593.100749] controller data-mesher[232]: time=2026-09-22T10:03:33.086Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> controller # [1564593.100874] controller systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> controller # [1564593.103007] controller systemd[1]: Starting Publish WireGuard controller info to data-mesher...
container-test-run-dm-wireguard-star> controller # [1564593.107637] controller data-mesher[232]: time=2026-09-22T10:03:33.093Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564593.107741] controller data-mesher[232]: time=2026-09-22T10:03:33.093Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564593.182734] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1564593.205708] controller data-mesher[232]: time=2026-09-22T10:03:33.191Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/controller status=204
container-test-run-dm-wireguard-star> controller # [1564593.206055] controller dm-wg-star-publish[297]: Status: 204 No Content
container-test-run-dm-wireguard-star> controller # [1564593.210316] controller systemd[1]: Finished Publish WireGuard controller info to data-mesher.
container-test-run-dm-wireguard-star> controller # [1564593.211145] controller systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> controller # [1564593.281646] controller dm-wg-star-reconfig[324]: No peer data available yet, skipping
container-test-run-dm-wireguard-star> controller # [1564593.283054] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1564593.336280] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> controller # [1564593.336954] controller systemd[1]: Startup finished in 13.142s.
container-test-run-dm-wireguard-star> peer1 # [1564593.100256] peer1 data-mesher[219]: time=2026-09-22T10:03:33.086Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564593.100256] peer1 data-mesher[219]: time=2026-09-22T10:03:33.086Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564593.107081] peer1 data-mesher[219]: time=2026-09-22T10:03:33.092Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer1 # [1564593.107173] peer1 data-mesher[219]: time=2026-09-22T10:03:33.092Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564593.107932] peer1 data-mesher[219]: time=2026-09-22T10:03:33.093Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564593.107932] peer1 data-mesher[219]: time=2026-09-22T10:03:33.093Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564593.108080] peer1 data-mesher[219]: time=2026-09-22T10:03:33.093Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer1 # [1564593.108179] peer1 data-mesher[219]: time=2026-09-22T10:03:33.093Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer1 # [1564593.108319] peer1 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer1 # [1564593.144845] peer1 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1564593.273520] peer1 data-mesher[219]: time=2026-09-22T10:03:33.259Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 status=204
container-test-run-dm-wireguard-star> peer1 # [1564593.273801] peer1 dm-wg-star-publish[286]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer1 # [1564593.276408] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1564593.276997] peer1 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1564593.277279] peer1 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1564593.279011] peer1 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer1 # [1564593.431259] peer1 dm-wg-star-reconfig[314]: No controller data available yet, skipping
container-test-run-dm-wireguard-star> peer1 # [1564593.432650] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1564593.432856] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1564593.436149] peer1 systemd[1]: Startup finished in 13.228s.
container-test-run-dm-wireguard-star> controller: (finished: waiting for unit data-mesher.service, in 14.18 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/v9vdqyj6qkb502yxrxqyp79njk9qi1ib-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/v9vdqyj6qkb502yxrxqyp79njk9qi1ib-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> controller # [1564598.101246] controller data-mesher[232]: time=2026-09-22T10:03:38.087Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1564598.101246] controller data-mesher[232]: time=2026-09-22T10:03:38.087Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564598.102016] controller data-mesher[232]: time=2026-09-22T10:03:38.087Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564598.102268] controller data-mesher[232]: time=2026-09-22T10:03:38.088Z level=DEBUG msg="new file detected" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1564598.102268] controller data-mesher[232]: time=2026-09-22T10:03:38.088Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564598.102339] controller data-mesher[232]: time=2026-09-22T10:03:38.088Z level=INFO msg="received file request" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1564598.102339] controller data-mesher[232]: time=2026-09-22T10:03:38.088Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1564598.102339] controller data-mesher[232]: time=2026-09-22T10:03:38.088Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1564598.102423] controller data-mesher[232]: time=2026-09-22T10:03:38.088Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 10:03:33.256 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564598.103944] controller data-mesher[232]: time=2026-09-22T10:03:38.089Z level=INFO msg="file transfer complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1564598.109203] controller data-mesher[232]: time=2026-09-22T10:03:38.094Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564598.109203] controller data-mesher[232]: time=2026-09-22T10:03:38.094Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564598.113576] controller data-mesher[232]: time=2026-09-22T10:03:38.095Z level=DEBUG msg="new file detected" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1564598.114634] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1564598.187789] controller data-mesher[232]: time=2026-09-22T10:03:38.173Z level=INFO msg="download complete" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 10:03:33.256 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt written=true elapsed=85.406212ms
container-test-run-dm-wireguard-star> controller # [1564598.274068] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1564598.274223] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1564598.101790] peer1 data-mesher[219]: time=2026-09-22T10:03:38.087Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564598.101790] peer1 data-mesher[219]: time=2026-09-22T10:03:38.087Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564598.102265] peer1 data-mesher[219]: time=2026-09-22T10:03:38.087Z level=DEBUG msg="new file detected" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1564598.102265] peer1 data-mesher[219]: time=2026-09-22T10:03:38.087Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1564598.102265] peer1 data-mesher[219]: time=2026-09-22T10:03:38.087Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 10:03:33.159 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564598.102650] peer1 data-mesher[219]: time=2026-09-22T10:03:38.088Z level=INFO msg="received file request" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer1 # [1564598.103352] peer1 data-mesher[219]: time=2026-09-22T10:03:38.089Z level=INFO msg="file transfer complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer1 # [1564598.107083] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1564598.113530] peer1 data-mesher[219]: time=2026-09-22T10:03:38.094Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1564598.113530] peer1 data-mesher[219]: time=2026-09-22T10:03:38.094Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564598.113530] peer1 data-mesher[219]: time=2026-09-22T10:03:38.095Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564598.113530] peer1 data-mesher[219]: time=2026-09-22T10:03:38.095Z level=DEBUG msg="new file detected" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer1 # [1564598.113530] peer1 data-mesher[219]: time=2026-09-22T10:03:38.095Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564598.113530] peer1 data-mesher[219]: time=2026-09-22T10:03:38.095Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1564598.187066] peer1 data-mesher[219]: time=2026-09-22T10:03:38.172Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 10:03:33.159 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us written=true elapsed=85.021326ms
container-test-run-dm-wireguard-star> peer1 # [1564598.272753] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1564598.273168] 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::294a:40f8:557b:bb3c
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::294a:40f8:557b:bb3c, in 4.45 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705, 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 # [1564603.103136] controller data-mesher[232]: time=2026-09-22T10:03:43.088Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1564603.103875] controller data-mesher[232]: time=2026-09-22T10:03:43.088Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564603.105059] controller data-mesher[232]: time=2026-09-22T10:03:43.090Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564603.105682] controller data-mesher[232]: time=2026-09-22T10:03:43.091Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564603.105768] controller data-mesher[232]: time=2026-09-22T10:03:43.091Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1564603.110765] controller data-mesher[232]: time=2026-09-22T10:03:43.096Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564603.110765] controller data-mesher[232]: time=2026-09-22T10:03:43.096Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller: (finished: must succeed: wg show wg-star peers | wc -l, in 0.02 seconds)
container-test-run-dm-wireguard-star> peer2: systemd-nspawn running (pid 786)
container-test-run-dm-wireguard-star> peer2: Waiting for journal at /build/vm-state-peer2/var/log/journal...
container-test-run-dm-wireguard-star> peer2: waiting for unit data-mesher.service
container-test-run-dm-wireguard-star> nixos-nspawn(peer2): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-dm-wireguard-star> nixos-nspawn(peer2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-dm-wireguard-star> peer1 # [1564603.104061] peer1 data-mesher[219]: time=2026-09-22T10:03:43.089Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564603.104061] peer1 data-mesher[219]: time=2026-09-22T10:03:43.089Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564603.110155] peer1 data-mesher[219]: time=2026-09-22T10:03:43.095Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1564603.110254] peer1 data-mesher[219]: time=2026-09-22T10:03:43.096Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564603.111727] peer1 data-mesher[219]: time=2026-09-22T10:03:43.097Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564603.112355] peer1 data-mesher[219]: time=2026-09-22T10:03:43.098Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564603.112415] peer1 data-mesher[219]: time=2026-09-22T10:03:43.098Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-dm-wireguard-star> ░ Spawning container peer2 on /build/vm-state-peer2.
container-test-run-dm-wireguard-star> peer2 # No journal files were found.
container-test-run-dm-wireguard-star> peer2 # No journal boot entry found for the specified boot (+0).
container-test-run-dm-wireguard-star> peer2 # [1564604.594061] peer2 systemd-journald[99]: Journal started
container-test-run-dm-wireguard-star> peer2 # [1564604.594122] peer2 systemd-journald[99]: Runtime Journal (/run/log/journal/24aaf9edb7aa4a4b9c355eed7768caba) is 8M, max 2.5G, 2.4G free.
container-test-run-dm-wireguard-star> peer2 # [1564604.615134] peer2 systemd[1]: Finished Apply Kernel Variables.
container-test-run-dm-wireguard-star> peer2 # [1564604.632757] peer2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-dm-wireguard-star> peer2 # [1564604.652795] peer2 systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-dm-wireguard-star> peer2 # [1564604.653739] peer2 systemd[1]: Starting Network Name Resolution...
container-test-run-dm-wireguard-star> peer2 # [1564604.654502] peer2 systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-dm-wireguard-star> peer2 # [1564604.707236] peer2 systemd-journald[99]: Time spent on flushing to /var/log/journal/24aaf9edb7aa4a4b9c355eed7768caba is 1.798ms for 7 entries.
container-test-run-dm-wireguard-star> peer2 # [1564604.707236] peer2 systemd-journald[99]: System Journal (/var/log/journal/24aaf9edb7aa4a4b9c355eed7768caba) is 8M, max 4G, 3.9G free.
container-test-run-dm-wireguard-star> peer2 # [1564604.720377] peer2 systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-dm-wireguard-star> peer2 # [1564604.721021] peer2 systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [1564604.721144] peer2 systemd[1]: Reached target Local File Systems.
container-test-run-dm-wireguard-star> peer2 # [1564604.721988] peer2 systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-dm-wireguard-star> peer2 # [1564604.722033] peer2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [1564604.722963] peer2 systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-dm-wireguard-star> peer2 # [1564604.722998] peer2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-dm-wireguard-star> peer2 # [1564604.742755] peer2 systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-dm-wireguard-star> peer2 # [1564604.744393] peer2 systemd[1]: Starting Create System Files and Directories...
container-test-run-dm-wireguard-star> peer2 # [1564604.762554] peer2 systemd-tmpfiles[191]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1564604.762768] peer2 systemd-tmpfiles[191]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1564604.762914] peer2 systemd-tmpfiles[191]: fchmod() of /var/log/journal/24aaf9edb7aa4a4b9c355eed7768caba failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1564604.763135] peer2 systemd-tmpfiles[191]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-dm-wireguard-star> peer2 # [1564604.764682] peer2 systemd[1]: Finished Create System Files and Directories.
container-test-run-dm-wireguard-star> peer2 # [1564604.766021] peer2 systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-dm-wireguard-star> peer2 # [1564604.766957] peer2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-dm-wireguard-star> peer2 # [1564604.779036] peer2 systemd[1]: Finished Firewall.
container-test-run-dm-wireguard-star> peer2 # [1564604.782324] peer2 systemd[1]: Reached target Preparation for Network.
container-test-run-dm-wireguard-star> peer2 # [1564604.783102] peer2 systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-dm-wireguard-star> peer2 # [1564604.784506] peer2 systemd[1]: Starting Network Management...
container-test-run-dm-wireguard-star> peer2 # [1564604.787077] peer2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-dm-wireguard-star> peer2 # [1564604.790258] peer2 systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-dm-wireguard-star> peer2 # [1564604.791416] peer2 systemd[1]: Starting Update is Completed...
container-test-run-dm-wireguard-star> peer2 # [1564604.804499] peer2 systemd[1]: Finished Update is Completed.
container-test-run-dm-wireguard-star> peer2 # [1564605.517170] 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 # [1564605.517262] 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 # [1564605.528083] 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 # [1564605.528257] 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 # [1564605.528428] peer2 systemd-networkd[212]: lo: Link UP
container-test-run-dm-wireguard-star> peer2 # [1564605.528432] peer2 systemd-networkd[212]: lo: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1564605.528640] peer2 systemd-networkd[212]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-dm-wireguard-star> peer2 # [1564605.529135] peer2 systemd[1]: Started Network Management.
container-test-run-dm-wireguard-star> peer2 # [1564605.529615] peer2 systemd-networkd[212]: wg-star: Configuring with /etc/systemd/network/40-wg-star.network.
container-test-run-dm-wireguard-star> peer2 # [1564605.530481] peer2 systemd-networkd[212]: wg-star: netdev ready
container-test-run-dm-wireguard-star> peer2 # [1564605.530938] peer2 systemd-networkd[212]: eth1: Link UP
container-test-run-dm-wireguard-star> peer2 # [1564605.530955] peer2 systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-dm-wireguard-star> peer2 # [1564605.531156] peer2 systemd-networkd[212]: eth1: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1564605.576674] peer2 systemd-networkd[212]: wg-star: Link UP
container-test-run-dm-wireguard-star> peer2 # [1564605.576680] peer2 systemd-networkd[212]: wg-star: Gained carrier
container-test-run-dm-wireguard-star> peer2 # [1564605.606812] peer2 systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-dm-wireguard-star> peer2 # [1564605.957263] peer2 systemd-resolved[145]: Positive Trust Anchors:
container-test-run-dm-wireguard-star> peer2 # [1564605.957275] peer2 systemd-resolved[145]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-dm-wireguard-star> peer2 # [1564605.957278] peer2 systemd-resolved[145]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-dm-wireguard-star> peer2 # [1564605.957313] peer2 systemd-resolved[145]: 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 # [1564605.979441] peer2 systemd-resolved[145]: Using system hostname 'peer2'.
container-test-run-dm-wireguard-star> peer2 # [1564605.981094] peer2 systemd[1]: Started Network Name Resolution.
container-test-run-dm-wireguard-star> peer2 # [1564605.981264] peer2 systemd[1]: Reached target Network.
container-test-run-dm-wireguard-star> peer2 # [1564605.981379] peer2 systemd[1]: Reached target System Initialization.
container-test-run-dm-wireguard-star> peer2 # [1564605.981544] peer2 systemd[1]: Started Watch for WireGuard controller info from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1564605.981611] peer2 systemd[1]: Started Periodic heartbeat re-publish for dm-wireguard-star peer.
container-test-run-dm-wireguard-star> peer2 # [1564605.981669] peer2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-dm-wireguard-star> peer2 # [1564605.981719] peer2 systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-dm-wireguard-star> peer2 # [1564605.981765] peer2 systemd[1]: Reached target Path Units.
container-test-run-dm-wireguard-star> peer2 # [1564605.981838] peer2 systemd[1]: Reached target Timer Units.
container-test-run-dm-wireguard-star> peer2 # [1564605.982079] peer2 systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-dm-wireguard-star> peer2 # [1564605.982299] peer2 systemd[1]: Listening on Nix Daemon Socket.
container-test-run-dm-wireguard-star> peer2 # [1564605.982524] peer2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-dm-wireguard-star> peer2 # [1564605.982574] peer2 systemd[1]: Reached target Socket Units.
container-test-run-dm-wireguard-star> peer2 # [1564605.982654] peer2 systemd[1]: Reached target Basic System.
container-test-run-dm-wireguard-star> peer2 # [1564605.985118] peer2 systemd[1]: Starting data mesher daemon...
container-test-run-dm-wireguard-star> peer2 # [1564605.986440] peer2 systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-dm-wireguard-star> peer2 # [1564605.987958] peer2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-dm-wireguard-star> peer2 # [1564605.990257] peer2 systemd[1]: Starting D-Bus System Message Bus...
container-test-run-dm-wireguard-star> peer2 # [1564606.065939] peer2 systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-dm-wireguard-star> peer2 # [1564606.247780] peer2 nsncd[223]: Sep 22 10:03:46.233 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 # [1564606.247738] peer2 systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-dm-wireguard-star> peer2 # [1564606.247852] peer2 systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [1564606.247956] peer2 systemd[1]: Reached target User and Group Name Lookups.
container-test-run-dm-wireguard-star> peer2 # [1564606.250034] peer2 systemd[1]: Starting User Login Management...
container-test-run-dm-wireguard-star> peer2 # [1564606.251467] peer2 systemd[1]: Starting Permit User Sessions...
container-test-run-dm-wireguard-star> peer2 # [1564606.308995] peer2 systemd[1]: Finished Permit User Sessions.
container-test-run-dm-wireguard-star> peer2 # [1564606.310747] peer2 systemd[1]: Started Console Getty.
container-test-run-dm-wireguard-star> peer2 # [1564606.310830] peer2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-dm-wireguard-star> peer2 # [1564606.310870] peer2 systemd[1]: Reached target Login Prompts.
container-test-run-dm-wireguard-star> peer2 # [1564606.423707] peer2 dbus-broker-launch[224]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-dm-wireguard-star> peer2 # [1564606.424912] peer2 dbus-broker-launch[224]: NSS returned no entry for 'systemd-timesync'
container-test-run-dm-wireguard-star> peer2 # [1564606.424912] peer2 dbus-broker-launch[224]: Invalid user-name in /nix/store/d4rlxgnkyjff4mz022znw7p2dm8yg5z3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-dm-wireguard-star> peer2 # [1564606.425387] peer2 systemd[1]: Started D-Bus System Message Bus.
container-test-run-dm-wireguard-star> peer2 # [1564606.434715] peer2 dbus-broker-launch[224]: Ready
container-test-run-dm-wireguard-star> peer2 # [1564606.877167] peer2 data-mesher[221]: time=2026-09-22T10:03:46.862Z 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 # [1564606.878251] peer2 data-mesher[221]: time=2026-09-22T10:03:46.864Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us: [/dns/controller.clan/tcp/7946]} {12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt: [/dns/peer1.clan/tcp/7946]} {12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j: [/dns/peer2.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer2 # [1564606.878294] peer2 data-mesher[221]: time=2026-09-22T10:03:46.864Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-dm-wireguard-star> peer2 # [1564606.912172] peer2 systemd-networkd[212]: eth1: Gained IPv6LL
container-test-run-dm-wireguard-star> peer2 # [1564606.956516] peer2 data-mesher[221]: time=2026-09-22T10:03:46.942Z level=INFO msg="checking file integrity"
container-test-run-dm-wireguard-star> peer2 # [1564606.956995] peer2 data-mesher[221]: time=2026-09-22T10:03:46.942Z level=INFO msg="file integrity check complete"
container-test-run-dm-wireguard-star> peer2 # [1564606.962348] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="libp2p host created" peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j 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::bc72:afe2:902:125/tcp/7946]"
container-test-run-dm-wireguard-star> peer2 # [1564606.962395] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-dm-wireguard-star> peer2 # [1564606.962395] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [1564606.962395] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-dm-wireguard-star> peer2 # [1564606.962459] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="starting server"
container-test-run-dm-wireguard-star> peer2 # [1564606.962534] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-dm-wireguard-star> peer2 # [1564606.962686] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-dm-wireguard-star> peer2 # [1564606.962738] peer2 data-mesher[221]: time=2026-09-22T10:03:46.948Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-dm-wireguard-star> peer2 # [1564606.970767] peer2 data-mesher[221]: time=2026-09-22T10:03:46.956Z level=INFO msg="peer connected" peer_id=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt remote_addr=/ip6/2001:db8:1::2/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1564606.981861] peer2 data-mesher[221]: time=2026-09-22T10:03:46.967Z level=INFO msg="peer connected" peer_id=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-dm-wireguard-star> peer1 # [1564606.973942] peer1 data-mesher[219]: time=2026-09-22T10:03:46.959Z level=INFO msg="peer connected" peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j remote_addr=/ip6/2001:db8:1::3/tcp/7946
container-test-run-dm-wireguard-star> controller # [1564606.983729] controller data-mesher[232]: time=2026-09-22T10:03:46.969Z level=INFO msg="peer connected" peer_id=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-dm-wireguard-star> peer2 # [1564607.167821] peer2 systemd-logind[241]: New seat seat0.
container-test-run-dm-wireguard-star> peer2 # [1564607.168069] peer2 systemd[1]: Started User Login Management.
container-test-run-dm-wireguard-star> peer2 # [1564607.170436] peer2 systemd[1]: Starting linger-users.service...
container-test-run-dm-wireguard-star> peer2 # [1564607.227230] peer2 systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1564607.227465] peer2 systemd[1]: Finished linger-users.service.
container-test-run-dm-wireguard-star> controller # [1564608.106182] controller data-mesher[232]: time=2026-09-22T10:03:48.091Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1564608.106912] controller data-mesher[232]: time=2026-09-22T10:03:48.092Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1564608.108156] controller data-mesher[232]: time=2026-09-22T10:03:48.093Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1564608.108156] controller data-mesher[232]: time=2026-09-22T10:03:48.093Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1564608.108323] controller data-mesher[232]: time=2026-09-22T10:03:48.094Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1564608.109469] controller data-mesher[232]: time=2026-09-22T10:03:48.095Z level=INFO msg="received file request" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1564608.109568] controller data-mesher[232]: time=2026-09-22T10:03:48.095Z level=INFO msg="received file request" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1564608.109873] controller data-mesher[232]: time=2026-09-22T10:03:48.095Z level=INFO msg="file transfer complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> controller # [1564608.112917] controller data-mesher[232]: time=2026-09-22T10:03:48.098Z level=INFO msg="file transfer complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> controller # [1564608.113909] controller data-mesher[232]: time=2026-09-22T10:03:48.099Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564608.113909] controller data-mesher[232]: time=2026-09-22T10:03:48.099Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer1 # [1564608.113143] peer1 data-mesher[219]: time=2026-09-22T10:03:48.098Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1564608.113836] peer1 data-mesher[219]: time=2026-09-22T10:03:48.099Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564608.114959] peer1 data-mesher[219]: time=2026-09-22T10:03:48.100Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564608.116284] peer1 data-mesher[219]: time=2026-09-22T10:03:48.101Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564608.116284] peer1 data-mesher[219]: time=2026-09-22T10:03:48.101Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [1564608.107182] peer2 data-mesher[221]: time=2026-09-22T10:03:48.092Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564608.107182] peer2 data-mesher[221]: time=2026-09-22T10:03:48.093Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564608.107925] peer2 data-mesher[221]: time=2026-09-22T10:03:48.093Z level=DEBUG msg="new file detected" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer2 # [1564608.107925] peer2 data-mesher[221]: time=2026-09-22T10:03:48.093Z level=DEBUG msg="new file detected" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 name=dm_wg_star_wg_star/controller name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [1564608.108095] peer2 data-mesher[221]: time=2026-09-22T10:03:48.093Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8
container-test-run-dm-wireguard-star> peer2 # [1564608.108095] peer2 data-mesher[221]: time=2026-09-22T10:03:48.093Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/controller
container-test-run-dm-wireguard-star> peer2 # [1564608.108204] peer2 data-mesher[221]: time=2026-09-22T10:03:48.093Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 10:03:33.159 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564608.108204] peer2 data-mesher[221]: time=2026-09-22T10:03:48.093Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 10:03:33.256 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564608.117554] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1564608.153908] peer2 data-mesher[221]: time=2026-09-22T10:03:48.139Z level=INFO msg="download complete" name=dm_wg_star_wg_star/controller signed_at="2026-09-22 10:03:33.159 +0000 UTC" signed_by="YFMr5LaoCU96lIfeyuZcjQh1hWx93LjniZyJpnchrUw=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us written=true elapsed=45.827029ms
container-test-run-dm-wireguard-star> peer2 # [1564608.157925] peer2 data-mesher[221]: time=2026-09-22T10:03:48.143Z level=INFO msg="download complete" name=dm_wg_star_wg_star/aXt3BAr_OnJsbDvgCHY4IEK_7YNi18HfK9QdXGEdMO8 signed_at="2026-09-22 10:03:33.256 +0000 UTC" signed_by="aXt3BAr/OnJsbDvgCHY4IEK/7YNi18HfK9QdXGEdMO8=" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us written=true elapsed=49.838604ms
container-test-run-dm-wireguard-star> peer2 # [1564608.269149] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1564608.269450] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> controller # [1564613.108788] controller data-mesher[232]: time=2026-09-22T10:03:53.094Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1564613.108788] controller data-mesher[232]: time=2026-09-22T10:03:53.094Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564613.110416] controller data-mesher[232]: time=2026-09-22T10:03:53.096Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564613.111037] controller data-mesher[232]: time=2026-09-22T10:03:53.096Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> controller # [1564613.111123] controller data-mesher[232]: time=2026-09-22T10:03:53.096Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1564613.116486] controller data-mesher[232]: time=2026-09-22T10:03:53.102Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> controller # [1564613.116569] controller data-mesher[232]: time=2026-09-22T10:03:53.102Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer1 # [1564613.109481] peer1 data-mesher[219]: time=2026-09-22T10:03:53.095Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564613.109481] peer1 data-mesher[219]: time=2026-09-22T10:03:53.095Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564613.115869] peer1 data-mesher[219]: time=2026-09-22T10:03:53.101Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1564613.116081] peer1 data-mesher[219]: time=2026-09-22T10:03:53.101Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564613.117498] peer1 data-mesher[219]: time=2026-09-22T10:03:53.103Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer1 # [1564613.118079] peer1 data-mesher[219]: time=2026-09-22T10:03:53.103Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564613.118159] peer1 data-mesher[219]: time=2026-09-22T10:03:53.103Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2: still waiting for container 'peer2' to reach ready state...
container-test-run-dm-wireguard-star> peer2 # [1564616.963077] peer2 data-mesher[221]: time=2026-09-22T10:03:56.948Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-dm-wireguard-star> peer2 # [1564616.963077] peer2 data-mesher[221]: time=2026-09-22T10:03:56.948Z level=DEBUG msg="initiating state exchange" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1564616.964853] peer2 data-mesher[221]: time=2026-09-22T10:03:56.950Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564616.965465] peer2 data-mesher[221]: time=2026-09-22T10:03:56.951Z level=INFO msg="state exchange complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1564616.965538] peer2 data-mesher[221]: time=2026-09-22T10:03:56.951Z level=INFO msg="server started"
container-test-run-dm-wireguard-star> peer2 # [1564616.965697] peer2 data-mesher[221]: time=2026-09-22T10:03:56.951Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-dm-wireguard-star> peer2 # [1564616.965831] peer2 systemd[1]: Started data mesher daemon.
container-test-run-dm-wireguard-star> peer2 # [1564616.968065] peer2 systemd[1]: Starting Publish WireGuard peer info to data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1564617.128698] peer2 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer2 # [1564617.257754] peer2 data-mesher[221]: time=2026-09-22T10:03:57.243Z level=INFO msg=http_request uri=/files/dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg status=204
container-test-run-dm-wireguard-star> controller # [1564616.963861] controller data-mesher[232]: time=2026-09-22T10:03:56.949Z level=INFO msg="received state sync from peer" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1564616.963861] controller data-mesher[232]: time=2026-09-22T10:03:56.949Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer2 # [1564617.258037] peer2 dm-wg-star-publish[297]: Status: 204 No Content
container-test-run-dm-wireguard-star> peer2 # [1564617.263763] peer2 systemd[1]: dm-wg-star-wg-star-publish.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1564617.264165] peer2 systemd[1]: Finished Publish WireGuard peer info to data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1564617.265200] peer2 systemd[1]: Reached target Multi-User System.
container-test-run-dm-wireguard-star> peer2 # [1564617.285048] peer2 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1564617.285360] peer2 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1564617.796863] peer2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-dm-wireguard-star> peer2 # [1564617.799490] peer2 systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-dm-wireguard-star> peer2 # [1564617.800106] peer2 systemd[1]: Startup finished in 13.813s.
container-test-run-dm-wireguard-star> peer2: (finished: waiting for unit data-mesher.service, in 14.66 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/v9vdqyj6qkb502yxrxqyp79njk9qi1ib-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/v9vdqyj6qkb502yxrxqyp79njk9qi1ib-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-dm-wireguard-star> controller # [1564618.112168] controller data-mesher[232]: time=2026-09-22T10:03:58.097Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1564618.112168] controller data-mesher[232]: time=2026-09-22T10:03:58.097Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1564618.113347] controller data-mesher[232]: time=2026-09-22T10:03:58.099Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1564618.113665] controller data-mesher[232]: time=2026-09-22T10:03:58.099Z level=DEBUG msg="new file detected" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> controller # [1564618.113787] controller data-mesher[232]: time=2026-09-22T10:03:58.099Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> controller # [1564618.113866] controller data-mesher[232]: time=2026-09-22T10:03:58.099Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 10:03:57.107 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1564618.114002] controller data-mesher[232]: time=2026-09-22T10:03:58.099Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1564618.114041] controller data-mesher[232]: time=2026-09-22T10:03:58.099Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1564618.118036] controller systemd[1]: Starting Reconfigure WireGuard peers from data-mesher...
container-test-run-dm-wireguard-star> controller # [1564618.144708] controller data-mesher[232]: time=2026-09-22T10:03:58.130Z level=INFO msg="download complete" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 10:03:57.107 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j written=true elapsed=30.889384ms
container-test-run-dm-wireguard-star> controller # [1564618.262092] controller systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> controller # [1564618.262200] controller systemd[1]: Finished Reconfigure WireGuard peers from data-mesher.
container-test-run-dm-wireguard-star> peer1 # [1564618.119057] peer1 data-mesher[219]: time=2026-09-22T10:03:58.104Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1564618.119057] peer1 data-mesher[219]: time=2026-09-22T10:03:58.104Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564618.119919] peer1 data-mesher[219]: time=2026-09-22T10:03:58.105Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1564618.120278] peer1 data-mesher[219]: time=2026-09-22T10:03:58.105Z level=DEBUG msg="new file detected" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer1 # [1564618.120278] peer1 data-mesher[219]: time=2026-09-22T10:03:58.106Z level=INFO msg="scheduling file download" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer1 # [1564618.120385] peer1 data-mesher[219]: time=2026-09-22T10:03:58.106Z level=INFO msg="downloading file" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 10:03:57.107 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1564618.120385] peer1 data-mesher[219]: time=2026-09-22T10:03:58.106Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564618.120385] peer1 data-mesher[219]: time=2026-09-22T10:03:58.106Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1564618.144584] peer1 systemd[1]: Starting Reconfigure WireGuard with controller from data-mesher...
container-test-run-dm-wireguard-star> peer1 # [1564618.148012] peer1 data-mesher[219]: time=2026-09-22T10:03:58.133Z level=INFO msg="download complete" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg signed_at="2026-09-22 10:03:57.107 +0000 UTC" signed_by="9prwbMgqXnBQgfTm6/gWLtYRg8dUfREaPQUPth0ssOg=" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j written=true elapsed=27.7147ms
container-test-run-dm-wireguard-star> peer1 # [1564618.256725] peer1 systemd[1]: dm-wg-star-wg-star-reconfig.service: Deactivated successfully.
container-test-run-dm-wireguard-star> peer1 # [1564618.256981] peer1 systemd[1]: Finished Reconfigure WireGuard with controller from data-mesher.
container-test-run-dm-wireguard-star> peer2 # [1564618.112842] peer2 data-mesher[221]: time=2026-09-22T10:03:58.098Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564618.112842] peer2 data-mesher[221]: time=2026-09-22T10:03:58.098Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564618.114124] peer2 data-mesher[221]: time=2026-09-22T10:03:58.099Z level=INFO msg="received file request" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer2 # [1564618.114403] peer2 data-mesher[221]: time=2026-09-22T10:03:58.100Z level=INFO msg="file transfer complete" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer2 # [1564618.119503] peer2 data-mesher[221]: time=2026-09-22T10:03:58.105Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1564618.119503] peer2 data-mesher[221]: time=2026-09-22T10:03:58.105Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1564618.120549] peer2 data-mesher[221]: time=2026-09-22T10:03:58.106Z level=INFO msg="received file request" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
container-test-run-dm-wireguard-star> peer2 # [1564618.122221] peer2 data-mesher[221]: time=2026-09-22T10:03:58.108Z level=INFO msg="file transfer complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt network="RDouuAwMbAdTXUmNxQWpjLRYnH6+pi7Nh6nX4ovyK+M=" name=dm_wg_star_wg_star/9prwbMgqXnBQgfTm6_gWLtYRg8dUfREaPQUPth0ssOg
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::294a:40f8:557b:bb3c
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::294a:40f8:557b:bb3c, in 0.01 seconds)
container-test-run-dm-wireguard-star> controller: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125
container-test-run-dm-wireguard-star> controller: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer1: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125
container-test-run-dm-wireguard-star> peer1: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::bc72:afe2:0902:0125, in 0.01 seconds)
container-test-run-dm-wireguard-star> peer2: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705
container-test-run-dm-wireguard-star> peer2: (finished: waiting for success: ping -6 -c1 -W5 fda1:05c8:00::8472:04a5:acd7:7705, in 0.01 seconds)
container-test-run-dm-wireguard-star> (finished: run the VM test script, in 39.53 seconds)
container-test-run-dm-wireguard-star> peer1 # [1564621.967436] peer1 data-mesher[219]: time=2026-09-22T10:04:01.953Z level=INFO msg="received state sync from peer" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1564621.967436] peer1 data-mesher[219]: time=2026-09-22T10:04:01.953Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer2 # [1564621.966364] peer2 data-mesher[221]: time=2026-09-22T10:04:01.952Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-dm-wireguard-star> peer2 # [1564621.966364] peer2 data-mesher[221]: time=2026-09-22T10:04:01.952Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1564621.968523] peer2 data-mesher[221]: time=2026-09-22T10:04:01.954Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1564621.969685] peer2 data-mesher[221]: time=2026-09-22T10:04:01.955Z level=INFO msg="state exchange complete" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt timeout=5s
container-test-run-dm-wireguard-star> peer2 # [1564621.969746] peer2 data-mesher[221]: time=2026-09-22T10:04:01.955Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> controller # [1564623.115010] controller data-mesher[232]: time=2026-09-22T10:04:03.100Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> controller # [1564623.115010] controller data-mesher[232]: time=2026-09-22T10:04:03.100Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1564623.116455] controller data-mesher[232]: time=2026-09-22T10:04:03.102Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> controller # [1564623.117588] controller data-mesher[232]: time=2026-09-22T10:04:03.103Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> controller # [1564623.117650] controller data-mesher[232]: time=2026-09-22T10:04:03.103Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer1 # [1564623.121246] peer1 data-mesher[219]: time=2026-09-22T10:04:03.107Z level=DEBUG msg="attempting push/pull" peer_count=1
container-test-run-dm-wireguard-star> peer1 # [1564623.121246] peer1 data-mesher[219]: time=2026-09-22T10:04:03.107Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564623.123534] peer1 data-mesher[219]: time=2026-09-22T10:04:03.109Z level=INFO msg="merging remote state" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j
container-test-run-dm-wireguard-star> peer1 # [1564623.124750] peer1 data-mesher[219]: time=2026-09-22T10:04:03.110Z level=INFO msg="state exchange complete" peer=12D3KooWSR1VTT7uTamqBVrXRsRuNtmc592goLTELoyzQAV8oa2j timeout=5s
container-test-run-dm-wireguard-star> peer1 # [1564623.125236] peer1 data-mesher[219]: time=2026-09-22T10:04:03.110Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-dm-wireguard-star> peer2 # [1564623.115621] peer2 data-mesher[221]: time=2026-09-22T10:04:03.101Z level=INFO msg="received state sync from peer" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564623.115621] peer2 data-mesher[221]: time=2026-09-22T10:04:03.101Z level=INFO msg="merging remote state" peer=12D3KooWL5F9xCpwACKCpcbSuwXRViV8Xo6YrQSxoHM55nTbo9Us
container-test-run-dm-wireguard-star> peer2 # [1564623.121971] peer2 data-mesher[221]: time=2026-09-22T10:04:03.107Z level=INFO msg="received state sync from peer" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> peer2 # [1564623.121971] peer2 data-mesher[221]: time=2026-09-22T10:04:03.107Z level=INFO msg="merging remote state" peer=12D3KooWGv8ApM6U4v6pVLkDDQ5MspMap7hy6DDdeiPy8AnbufBt
container-test-run-dm-wireguard-star> test script finished in 44.08s
container-test-run-dm-wireguard-star> cleanup
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 50)
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 53)
container-test-run-dm-wireguard-star> Container controller terminated by signal KILL.
container-test-run-dm-wireguard-star> kill NspawnMachine (pid 786)
container-test-run-dm-wireguard-star> Container peer1 terminated by signal KILL.
container-test-run-dm-wireguard-star> Container peer2 terminated by signal KILL.
container-test-run-dm-wireguard-star> (finished: cleanup, in 0.64 seconds)

post-build step Upload to niks3: ok
time=2026-09-22T10:04:04.873Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-09-22T10:04:05.170Z level=INFO msg="Uploading 1 narinfos"
time=2026-09-22T10:04:05.296Z level=INFO msg="Upload complete. (513ms)"