don't know how to build these paths:
  /nix/store/36dxgf4xndn9cb223fzpc07nb33fbmaz-container-test-run-data-mesher.drv
warning: ignoring the client-specified setting 'store', because it is a restricted setting and you are not a trusted user
xy2glvl89lh97wl42ph0g0mkp5cjm2cp-firewall-reload.drv: build01: not scheduled here: xy2glvl89lh97wl42ph0g0mkp5cjm2cp-firewall-reload.drv, asking the scheduler again
building '/nix/store/1vr7rvlv68s1ciszsn9f2aw1325hd2hd-unit-systemd-networkd.service.drv' on nix-store.geninf.io:50051
unit-systemd-networkd.service> attached to the build already running on this worker
unit-systemd-networkd.service> build01: building 1vr7rvlv68s1ciszsn9f2aw1325hd2hd-unit-systemd-networkd.service.drv
unit-systemd-networkd.service> structuredAttrs is enabled
building '/nix/store/f2dn96riqycli3d495nqf5z0rxxj952f-etc-hostname.drv' on nix-store.geninf.io:50051
etc-hostname> build01: building f2dn96riqycli3d495nqf5z0rxxj952f-etc-hostname.drv
etc-hostname> structuredAttrs is enabled
building '/nix/store/jr309i5y0q5fipcwr94jhi651s5qr7vd-string-hosts.drv' on nix-store.geninf.io:50051
string-hosts> build01: building jr309i5y0q5fipcwr94jhi651s5qr7vd-string-hosts.drv
string-hosts> structuredAttrs is enabled
building '/nix/store/q0ppcis1fqqgi6djn6yly76rwnb8588z-test-script.drv' on nix-store.geninf.io:50051
test-script> build01: building q0ppcis1fqqgi6djn6yly76rwnb8588z-test-script.drv
test-script> structuredAttrs is enabled
building '/nix/store/vpc861wjpldkgbvvyrclcmyz4rx70ry4-etc-hostname.drv' on nix-store.geninf.io:50051
etc-hostname> build01: building vpc861wjpldkgbvvyrclcmyz4rx70ry4-etc-hostname.drv
building '/nix/store/54dqq0xa8g1ishnjjy115hb02d1pajwr-etc-hostname.drv' on nix-store.geninf.io:50051
etc-hostname> build01: building 54dqq0xa8g1ishnjjy115hb02d1pajwr-etc-hostname.drv
etc-hostname> structuredAttrs is enabled
etc-hostname> structuredAttrs is enabled
building '/nix/store/h3ywzss14f7c5q2sq60vfh93n4526qmw-string-hosts.drv' on nix-store.geninf.io:50051
string-hosts> build01: building h3ywzss14f7c5q2sq60vfh93n4526qmw-string-hosts.drv
string-hosts> structuredAttrs is enabled
building '/nix/store/afg5vgs1giw6s37dwhv1qi47l17na405-string-hosts.drv' on nix-store.geninf.io:50051
string-hosts> build01: building afg5vgs1giw6s37dwhv1qi47l17na405-string-hosts.drv
string-hosts> structuredAttrs is enabled
building '/nix/store/ix3y2qf0kxqs80v5qnr8qsbf9dr0r462-vars-check-data-mesher.drv' on nix-store.geninf.io:50051
vars-check-data-mesher> build01: building ix3y2qf0kxqs80v5qnr8qsbf9dr0r462-vars-check-data-mesher.drv
vars-check-data-mesher> Running vars check using Nix-based executor...
vars-check-data-mesher> ✓ All vars checks completed successfully
hif9aflv160c09wrl4lipf3vv8c0c6qd-users-groups.json.drv: build01: not scheduled here: hif9aflv160c09wrl4lipf3vv8c0c6qd-users-groups.json.drv, asking the scheduler again
building '/nix/store/hif9aflv160c09wrl4lipf3vv8c0c6qd-users-groups.json.drv' on nix-store.geninf.io:50051
users-groups.json> build01: building hif9aflv160c09wrl4lipf3vv8c0c6qd-users-groups.json.drv
users-groups.json> structuredAttrs is enabled
38cqabqg2z4i4hzvlc9fbar3bxiv4p0l-extra-hosts.drv: build01: not scheduled here: 38cqabqg2z4i4hzvlc9fbar3bxiv4p0l-extra-hosts.drv, asking the scheduler again
building '/nix/store/38cqabqg2z4i4hzvlc9fbar3bxiv4p0l-extra-hosts.drv' on nix-store.geninf.io:50051
extra-hosts> build01: building 38cqabqg2z4i4hzvlc9fbar3bxiv4p0l-extra-hosts.drv
extra-hosts> structuredAttrs is enabled
w4nph4ni26n9h1fak3hn7nyzw7y6xmn1-dm.toml.drv: build01: not scheduled here: w4nph4ni26n9h1fak3hn7nyzw7y6xmn1-dm.toml.drv, asking the scheduler again
qd1ib0vyd5zy3fv1dnlpj00l83pyifpz-dm.toml.drv: build01: not scheduled here: qd1ib0vyd5zy3fv1dnlpj00l83pyifpz-dm.toml.drv, asking the scheduler again
l4y6ca4kavpf2sllxng75b3ii1m8124i-dm.toml.drv: build01: not scheduled here: l4y6ca4kavpf2sllxng75b3ii1m8124i-dm.toml.drv, asking the scheduler again
building '/nix/store/w4nph4ni26n9h1fak3hn7nyzw7y6xmn1-dm.toml.drv' on nix-store.geninf.io:50051
dm.toml> build01: building w4nph4ni26n9h1fak3hn7nyzw7y6xmn1-dm.toml.drv
dm.toml> structuredAttrs is enabled
building '/nix/store/l4y6ca4kavpf2sllxng75b3ii1m8124i-dm.toml.drv' on nix-store.geninf.io:50051
dm.toml> build01: building l4y6ca4kavpf2sllxng75b3ii1m8124i-dm.toml.drv
building '/nix/store/qd1ib0vyd5zy3fv1dnlpj00l83pyifpz-dm.toml.drv' on nix-store.geninf.io:50051
dm.toml> build01: building qd1ib0vyd5zy3fv1dnlpj00l83pyifpz-dm.toml.drv
dm.toml> structuredAttrs is enabled
dm.toml> structuredAttrs is enabled
8bh5mx4db2bvfw52fhhirm9dp07dnk8z-decrypt-age-secrets.drv: build01: not scheduled here: 8bh5mx4db2bvfw52fhhirm9dp07dnk8z-decrypt-age-secrets.drv, asking the scheduler again
wd11qjhmjdms5malafcl56hqsk6gg748-decrypt-age-secrets.drv: build01: not scheduled here: wd11qjhmjdms5malafcl56hqsk6gg748-decrypt-age-secrets.drv, asking the scheduler again
x8w6casgqqg6jvflkbjszf8irbyq1b7x-decrypt-age-secrets.drv: build01: not scheduled here: x8w6casgqqg6jvflkbjszf8irbyq1b7x-decrypt-age-secrets.drv, asking the scheduler again
building '/nix/store/wd11qjhmjdms5malafcl56hqsk6gg748-decrypt-age-secrets.drv' on nix-store.geninf.io:50051
decrypt-age-secrets> build01: building wd11qjhmjdms5malafcl56hqsk6gg748-decrypt-age-secrets.drv
building '/nix/store/8bh5mx4db2bvfw52fhhirm9dp07dnk8z-decrypt-age-secrets.drv' on nix-store.geninf.io:50051
decrypt-age-secrets> build01: building 8bh5mx4db2bvfw52fhhirm9dp07dnk8z-decrypt-age-secrets.drv
decrypt-age-secrets> structuredAttrs is enabled
decrypt-age-secrets> structuredAttrs is enabled
building '/nix/store/x8w6casgqqg6jvflkbjszf8irbyq1b7x-decrypt-age-secrets.drv' on nix-store.geninf.io:50051
decrypt-age-secrets> build01: building x8w6casgqqg6jvflkbjszf8irbyq1b7x-decrypt-age-secrets.drv
decrypt-age-secrets> structuredAttrs is enabled
building '/nix/store/g1gxpf31b04lk2di86slc8krhipbwqyz-hosts.drv' on nix-store.geninf.io:50051
hosts> build01: building g1gxpf31b04lk2di86slc8krhipbwqyz-hosts.drv
5fz0iypzj63lfis57d1c256035miikss-hosts.drv: build01: not scheduled here: 5fz0iypzj63lfis57d1c256035miikss-hosts.drv, asking the scheduler again
8glwr4xyg74a6bc0ziz5bj0v6fciny0i-hosts.drv: build01: not scheduled here: 8glwr4xyg74a6bc0ziz5bj0v6fciny0i-hosts.drv, asking the scheduler again
building '/nix/store/8glwr4xyg74a6bc0ziz5bj0v6fciny0i-hosts.drv' on nix-store.geninf.io:50051
hosts> build01: building 8glwr4xyg74a6bc0ziz5bj0v6fciny0i-hosts.drv
building '/nix/store/5fz0iypzj63lfis57d1c256035miikss-hosts.drv' on nix-store.geninf.io:50051
hosts> build01: building 5fz0iypzj63lfis57d1c256035miikss-hosts.drv
dhw0yz27c7cx1623fm6h2ynhwny26ws6-unit-firewall.service.drv: build01: not scheduled here: dhw0yz27c7cx1623fm6h2ynhwny26ws6-unit-firewall.service.drv, asking the scheduler again
building '/nix/store/dhw0yz27c7cx1623fm6h2ynhwny26ws6-unit-firewall.service.drv' on nix-store.geninf.io:50051
unit-firewall.service> build01: building dhw0yz27c7cx1623fm6h2ynhwny26ws6-unit-firewall.service.drv
unit-firewall.service> structuredAttrs is enabled
bqjpf4zpac4vkrsarfchxp6g69yy2n2g-X-Restart-Triggers-data-mesher.drv: build01: not scheduled here: bqjpf4zpac4vkrsarfchxp6g69yy2n2g-X-Restart-Triggers-data-mesher.drv, asking the scheduler again
building '/nix/store/bqjpf4zpac4vkrsarfchxp6g69yy2n2g-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051
X-Restart-Triggers-data-mesher> build01: building bqjpf4zpac4vkrsarfchxp6g69yy2n2g-X-Restart-Triggers-data-mesher.drv
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
1m9bspk438g07z7q17nl8xl8fnk3xc2w-X-Restart-Triggers-data-mesher.drv: build01: not scheduled here: 1m9bspk438g07z7q17nl8xl8fnk3xc2w-X-Restart-Triggers-data-mesher.drv, asking the scheduler again
building '/nix/store/1m9bspk438g07z7q17nl8xl8fnk3xc2w-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051
X-Restart-Triggers-data-mesher> build01: building 1m9bspk438g07z7q17nl8xl8fnk3xc2w-X-Restart-Triggers-data-mesher.drv
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
h2k4wpr716rk3ngn86xzfzxazn3zbhi4-X-Restart-Triggers-data-mesher.drv: build01: not scheduled here: h2k4wpr716rk3ngn86xzfzxazn3zbhi4-X-Restart-Triggers-data-mesher.drv, asking the scheduler again
building '/nix/store/h2k4wpr716rk3ngn86xzfzxazn3zbhi4-X-Restart-Triggers-data-mesher.drv' on nix-store.geninf.io:50051
X-Restart-Triggers-data-mesher> build01: building h2k4wpr716rk3ngn86xzfzxazn3zbhi4-X-Restart-Triggers-data-mesher.drv
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
mlii90m9pdnlwylz1vrwirfgfg9is99w-dry-activate.drv: build01: not scheduled here: mlii90m9pdnlwylz1vrwirfgfg9is99w-dry-activate.drv, asking the scheduler again
building '/nix/store/mlii90m9pdnlwylz1vrwirfgfg9is99w-dry-activate.drv' on nix-store.geninf.io:50051
dry-activate> build01: building mlii90m9pdnlwylz1vrwirfgfg9is99w-dry-activate.drv
dry-activate> structuredAttrs is enabled
wm6xr9rs952frvnmvgc01j6h69fv937x-dry-activate.drv: build01: not scheduled here: wm6xr9rs952frvnmvgc01j6h69fv937x-dry-activate.drv, asking the scheduler again
building '/nix/store/wm6xr9rs952frvnmvgc01j6h69fv937x-dry-activate.drv' on nix-store.geninf.io:50051
dry-activate> build01: building wm6xr9rs952frvnmvgc01j6h69fv937x-dry-activate.drv
dry-activate> structuredAttrs is enabled
xax4msqfdcxp8cryss4x6ycnzav7pfss-dry-activate.drv: build01: not scheduled here: xax4msqfdcxp8cryss4x6ycnzav7pfss-dry-activate.drv, asking the scheduler again
building '/nix/store/xax4msqfdcxp8cryss4x6ycnzav7pfss-dry-activate.drv' on nix-store.geninf.io:50051
dry-activate> build01: building xax4msqfdcxp8cryss4x6ycnzav7pfss-dry-activate.drv
dry-activate> structuredAttrs is enabled
pd4vzwbnsi4kqj5la4czj40wfys8y493-system-path.drv: build01: not scheduled here: pd4vzwbnsi4kqj5la4czj40wfys8y493-system-path.drv, asking the scheduler again
building '/nix/store/pd4vzwbnsi4kqj5la4czj40wfys8y493-system-path.drv' on nix-store.geninf.io:50051
system-path> build01: building pd4vzwbnsi4kqj5la4czj40wfys8y493-system-path.drv
system-path> structuredAttrs is enabled
system-path> created 1716 symlinks in user environment
xr6x5yzr0iavmc1dnv0fwynwvxaaxbwc-unit-data-mesher.service.drv: build01: not scheduled here: xr6x5yzr0iavmc1dnv0fwynwvxaaxbwc-unit-data-mesher.service.drv, asking the scheduler again
building '/nix/store/xr6x5yzr0iavmc1dnv0fwynwvxaaxbwc-unit-data-mesher.service.drv' on nix-store.geninf.io:50051
unit-data-mesher.service> build01: building xr6x5yzr0iavmc1dnv0fwynwvxaaxbwc-unit-data-mesher.service.drv
unit-data-mesher.service> structuredAttrs is enabled
0hj0xnpnhxbakvmm5b8w391y9001lvkk-unit-data-mesher.service.drv: build01: not scheduled here: 0hj0xnpnhxbakvmm5b8w391y9001lvkk-unit-data-mesher.service.drv, asking the scheduler again
building '/nix/store/0hj0xnpnhxbakvmm5b8w391y9001lvkk-unit-data-mesher.service.drv' on nix-store.geninf.io:50051
unit-data-mesher.service> build01: building 0hj0xnpnhxbakvmm5b8w391y9001lvkk-unit-data-mesher.service.drv
unit-data-mesher.service> structuredAttrs is enabled
wgw59ifqrlxngmjb99gxz0h3vgcjjfnd-unit-data-mesher.service.drv: build01: not scheduled here: wgw59ifqrlxngmjb99gxz0h3vgcjjfnd-unit-data-mesher.service.drv, asking the scheduler again
building '/nix/store/wgw59ifqrlxngmjb99gxz0h3vgcjjfnd-unit-data-mesher.service.drv' on nix-store.geninf.io:50051
unit-data-mesher.service> build01: building wgw59ifqrlxngmjb99gxz0h3vgcjjfnd-unit-data-mesher.service.drv
unit-data-mesher.service> structuredAttrs is enabled
mlsrwp9zbl4n4l1z93sw19gpglmv3w1x-dbus-1.drv: build01: not scheduled here: mlsrwp9zbl4n4l1z93sw19gpglmv3w1x-dbus-1.drv, asking the scheduler again
building '/nix/store/mlsrwp9zbl4n4l1z93sw19gpglmv3w1x-dbus-1.drv' on nix-store.geninf.io:50051
dbus-1> build01: building mlsrwp9zbl4n4l1z93sw19gpglmv3w1x-dbus-1.drv
nv35z7d1xjr025dp7ssdgak4mmc7g90k-X-Restart-Triggers-dbus-broker.drv: build01: not scheduled here: nv35z7d1xjr025dp7ssdgak4mmc7g90k-X-Restart-Triggers-dbus-broker.drv, asking the scheduler again
building '/nix/store/nv35z7d1xjr025dp7ssdgak4mmc7g90k-X-Restart-Triggers-dbus-broker.drv' on nix-store.geninf.io:50051
X-Restart-Triggers-dbus-broker> build01: building nv35z7d1xjr025dp7ssdgak4mmc7g90k-X-Restart-Triggers-dbus-broker.drv
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
2l3i6ng8g9n3f1lnr6948v2mkazfr825-unit-dbus-broker.service.drv: build01: not scheduled here: 2l3i6ng8g9n3f1lnr6948v2mkazfr825-unit-dbus-broker.service.drv, asking the scheduler again
building '/nix/store/2l3i6ng8g9n3f1lnr6948v2mkazfr825-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051
unit-dbus-broker.service> build01: building 2l3i6ng8g9n3f1lnr6948v2mkazfr825-unit-dbus-broker.service.drv
unit-dbus-broker.service> structuredAttrs is enabled
hqb5pqilx8pkdvi61gwdr4r55m05qvbg-unit-dbus-broker.service.drv: build01: not scheduled here: hqb5pqilx8pkdvi61gwdr4r55m05qvbg-unit-dbus-broker.service.drv, asking the scheduler again
building '/nix/store/hqb5pqilx8pkdvi61gwdr4r55m05qvbg-unit-dbus-broker.service.drv' on nix-store.geninf.io:50051
unit-dbus-broker.service> build01: building hqb5pqilx8pkdvi61gwdr4r55m05qvbg-unit-dbus-broker.service.drv
unit-dbus-broker.service> structuredAttrs is enabled
1pf227wdxb97f225kvi3ls17c6mlh7a6-user-units.drv: build01: not scheduled here: 1pf227wdxb97f225kvi3ls17c6mlh7a6-user-units.drv, asking the scheduler again
py19vy32xyv8lm79wipkpsxin8pzb4vg-system-units.drv: build01: not scheduled here: py19vy32xyv8lm79wipkpsxin8pzb4vg-system-units.drv, asking the scheduler again
awgfgwsdvgnz1vb6qmmwyl6aiav47gpd-system-units.drv: build01: not scheduled here: awgfgwsdvgnz1vb6qmmwyl6aiav47gpd-system-units.drv, asking the scheduler again
kgaqrlzd2nv4z6l6rjl53aqv2ycxjvrv-system-units.drv: build01: not scheduled here: kgaqrlzd2nv4z6l6rjl53aqv2ycxjvrv-system-units.drv, asking the scheduler again
building '/nix/store/1pf227wdxb97f225kvi3ls17c6mlh7a6-user-units.drv' on nix-store.geninf.io:50051
user-units> build01: building 1pf227wdxb97f225kvi3ls17c6mlh7a6-user-units.drv
building '/nix/store/awgfgwsdvgnz1vb6qmmwyl6aiav47gpd-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building awgfgwsdvgnz1vb6qmmwyl6aiav47gpd-system-units.drv
building '/nix/store/py19vy32xyv8lm79wipkpsxin8pzb4vg-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building py19vy32xyv8lm79wipkpsxin8pzb4vg-system-units.drv
building '/nix/store/kgaqrlzd2nv4z6l6rjl53aqv2ycxjvrv-system-units.drv' on nix-store.geninf.io:50051
system-units> build01: building kgaqrlzd2nv4z6l6rjl53aqv2ycxjvrv-system-units.drv
building '/nix/store/apy10fv0ah7jwn6agwwzm0n9lshqgnhx-etc.drv' on nix-store.geninf.io:50051
etc> build01: building apy10fv0ah7jwn6agwwzm0n9lshqgnhx-etc.drv
building '/nix/store/023pmvmr3kihzswl2rzkn7r066zslkx8-etc.drv' on nix-store.geninf.io:50051
etc> build01: building 023pmvmr3kihzswl2rzkn7r066zslkx8-etc.drv
building '/nix/store/rmi5vnh7b9vb1642f0az1i2bikr29myq-etc.drv' on nix-store.geninf.io:50051
etc> build01: building rmi5vnh7b9vb1642f0az1i2bikr29myq-etc.drv
building '/nix/store/w94w6pq09vfxlh46wba9a0kcfp0n7f5s-activate.drv' on nix-store.geninf.io:50051
activate> build01: building w94w6pq09vfxlh46wba9a0kcfp0n7f5s-activate.drv
activate> structuredAttrs is enabled
building '/nix/store/df4kp6k1iz0508h8gjyv16mnx4l96ji9-activate.drv' on nix-store.geninf.io:50051
activate> build01: building df4kp6k1iz0508h8gjyv16mnx4l96ji9-activate.drv
activate> structuredAttrs is enabled
building '/nix/store/2r8gm08ilwmykclnwix1minxk65x2xxp-activate.drv' on nix-store.geninf.io:50051
activate> build01: building 2r8gm08ilwmykclnwix1minxk65x2xxp-activate.drv
activate> structuredAttrs is enabled
dzr3w3lfam2zf2i174q7pd0kdnhki3bk-nixos-system-gamma-test.drv: build01: not scheduled here: dzr3w3lfam2zf2i174q7pd0kdnhki3bk-nixos-system-gamma-test.drv, asking the scheduler again
8jnp6sdjd366dxlkbgarg9n881dnx2sz-nixos-system-beta-test.drv: build01: not scheduled here: 8jnp6sdjd366dxlkbgarg9n881dnx2sz-nixos-system-beta-test.drv, asking the scheduler again
5gbxvfzbmpkz8b6isblszivvz10s84h1-nixos-system-alpha-test.drv: build01: not scheduled here: 5gbxvfzbmpkz8b6isblszivvz10s84h1-nixos-system-alpha-test.drv, asking the scheduler again
building '/nix/store/dzr3w3lfam2zf2i174q7pd0kdnhki3bk-nixos-system-gamma-test.drv' on nix-store.geninf.io:50051
nixos-system-gamma-test> build01: building dzr3w3lfam2zf2i174q7pd0kdnhki3bk-nixos-system-gamma-test.drv
nixos-system-gamma-test> structuredAttrs is enabled
building '/nix/store/8jnp6sdjd366dxlkbgarg9n881dnx2sz-nixos-system-beta-test.drv' on nix-store.geninf.io:50051
nixos-system-beta-test> build01: building 8jnp6sdjd366dxlkbgarg9n881dnx2sz-nixos-system-beta-test.drv
nixos-system-beta-test> structuredAttrs is enabled
building '/nix/store/5gbxvfzbmpkz8b6isblszivvz10s84h1-nixos-system-alpha-test.drv' on nix-store.geninf.io:50051
nixos-system-alpha-test> build01: building 5gbxvfzbmpkz8b6isblszivvz10s84h1-nixos-system-alpha-test.drv
nixos-system-alpha-test> structuredAttrs is enabled
building '/nix/store/g43dl3bqvgylwr1qll2f473kwl49h5pl-run-gamma-nspawn.drv' on nix-store.geninf.io:50051
run-gamma-nspawn> build01: building g43dl3bqvgylwr1qll2f473kwl49h5pl-run-gamma-nspawn.drv
ajn2x13dmlsrf4cabz5i6jrwa94ji3sz-run-beta-nspawn.drv: build01: not scheduled here: ajn2x13dmlsrf4cabz5i6jrwa94ji3sz-run-beta-nspawn.drv, asking the scheduler again
building '/nix/store/ajn2x13dmlsrf4cabz5i6jrwa94ji3sz-run-beta-nspawn.drv' on nix-store.geninf.io:50051
run-beta-nspawn> build01: building ajn2x13dmlsrf4cabz5i6jrwa94ji3sz-run-beta-nspawn.drv
03rk0g0bkis3i0py1sdn94rpgd0yqi2d-run-alpha-nspawn.drv: build01: not scheduled here: 03rk0g0bkis3i0py1sdn94rpgd0yqi2d-run-alpha-nspawn.drv, asking the scheduler again
building '/nix/store/03rk0g0bkis3i0py1sdn94rpgd0yqi2d-run-alpha-nspawn.drv' on nix-store.geninf.io:50051
run-alpha-nspawn> build01: building 03rk0g0bkis3i0py1sdn94rpgd0yqi2d-run-alpha-nspawn.drv
7rxi9sba9ijhq4fchzdx5xbkid13xl5w-driverConfiguration.json.drv: build01: not scheduled here: 7rxi9sba9ijhq4fchzdx5xbkid13xl5w-driverConfiguration.json.drv, asking the scheduler again
building '/nix/store/7rxi9sba9ijhq4fchzdx5xbkid13xl5w-driverConfiguration.json.drv' on nix-store.geninf.io:50051
driverConfiguration.json> build01: building 7rxi9sba9ijhq4fchzdx5xbkid13xl5w-driverConfiguration.json.drv
driverConfiguration.json> structuredAttrs is enabled
zrdb01gm2pjyj19wb83px0lziy42qh51-nixos-test-driver-data-mesher.drv: build01: not scheduled here: zrdb01gm2pjyj19wb83px0lziy42qh51-nixos-test-driver-data-mesher.drv, asking the scheduler again
building '/nix/store/zrdb01gm2pjyj19wb83px0lziy42qh51-nixos-test-driver-data-mesher.drv' on nix-store.geninf.io:50051
nixos-test-driver-data-mesher> build01: building zrdb01gm2pjyj19wb83px0lziy42qh51-nixos-test-driver-data-mesher.drv
nixos-test-driver-data-mesher> Running type check (enable/disable: config.skipTypeCheck)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
nixos-test-driver-data-mesher> All checks passed!
nixos-test-driver-data-mesher> Linting test script (enable/disable: config.skipLint)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
nixos-test-driver-data-mesher> All checks passed!
36dxgf4xndn9cb223fzpc07nb33fbmaz-container-test-run-data-mesher.drv: build01: not scheduled here: 36dxgf4xndn9cb223fzpc07nb33fbmaz-container-test-run-data-mesher.drv, asking the scheduler again
building '/nix/store/36dxgf4xndn9cb223fzpc07nb33fbmaz-container-test-run-data-mesher.drv' on nix-store.geninf.io:50051
container-test-run-data-mesher> build01: building 36dxgf4xndn9cb223fzpc07nb33fbmaz-container-test-run-data-mesher.drv
container-test-run-data-mesher> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-data-mesher> start all VLans
container-test-run-data-mesher> (finished: start all VLans, in 0.00 seconds)
container-test-run-data-mesher> 
container-test-run-data-mesher> Test will time out and terminate in 3600.0 seconds
container-test-run-data-mesher> run the VM test script
container-test-run-data-mesher> additionally exposed symbols:
container-test-run-data-mesher>     alpha, beta, gamma,
container-test-run-data-mesher>     vlan1,
container-test-run-data-mesher>     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-data-mesher> start all VMs
container-test-run-data-mesher> alpha: systemd-nspawn running (pid 53)
container-test-run-data-mesher> beta: systemd-nspawn running (pid 54)
container-test-run-data-mesher> gamma: systemd-nspawn running (pid 55)
container-test-run-data-mesher> alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...
container-test-run-data-mesher> gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...
container-test-run-data-mesher> beta: Waiting for journal at /build/vm-state-beta/var/log/journal...
container-test-run-data-mesher> (finished: start all VMs, in 0.00 seconds)
container-test-run-data-mesher> alpha: waiting for unit data-mesher.service
container-test-run-data-mesher> nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(beta): 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-data-mesher> nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(alpha): 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-data-mesher> nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(gamma): 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-data-mesher> 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-data-mesher> 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-data-mesher> 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-data-mesher> ░ Spawning container alpha on /build/vm-state-alpha.
container-test-run-data-mesher> ░ Spawning container beta on /build/vm-state-beta.
container-test-run-data-mesher> ░ Spawning container gamma on /build/vm-state-gamma.
container-test-run-data-mesher> gamma # No journal files were found.
container-test-run-data-mesher> gamma # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> alpha # No journal files were found.
container-test-run-data-mesher> alpha # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> beta # No journal files were found.
container-test-run-data-mesher> beta # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> gamma # [2258519.855258] gamma systemd-journald[90]: Journal started
container-test-run-data-mesher> gamma # [2258519.855319] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/35c8102503b5422da77ade95c9225616) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> gamma # [2258519.862999] gamma systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> gamma # [2258519.874758] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> gamma # [2258519.888176] gamma systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> gamma # [2258519.889163] gamma systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> gamma # [2258519.889778] gamma systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> gamma # [2258519.898270] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/35c8102503b5422da77ade95c9225616 is 1.103ms for 7 entries.
container-test-run-data-mesher> gamma # [2258519.898270] gamma systemd-journald[90]: System Journal (/var/log/journal/35c8102503b5422da77ade95c9225616) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> gamma # [2258519.926341] gamma systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> gamma # [2258519.934293] gamma systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [2258519.935473] gamma systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [2258519.935576] gamma systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [2258519.936490] gamma systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> gamma # [2258519.936539] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [2258519.937350] gamma systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [2258519.938182] gamma systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> gamma # [2258519.938218] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [2258519.957020] gamma systemd-tmpfiles[151]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [2258519.957235] gamma systemd-tmpfiles[151]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [2258519.957384] gamma systemd-tmpfiles[151]: fchmod() of /var/log/journal/35c8102503b5422da77ade95c9225616 failed: Operation not permitted
container-test-run-data-mesher> gamma # [2258519.957607] gamma systemd-tmpfiles[151]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [2258519.959045] gamma systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [2258519.960059] gamma systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [2258519.960808] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [2258519.973756] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> gamma # [2258519.981721] gamma systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> gamma # [2258519.982825] gamma systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [2258519.993082] gamma systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [2258520.032362] gamma systemd[1]: Finished Firewall.
container-test-run-data-mesher> gamma # [2258520.032917] gamma systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [2258520.033193] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [2258520.034472] gamma systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [2258520.703276] gamma systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [2258520.703361] gamma systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [2258520.712869] gamma systemd-networkd[207]: /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-data-mesher> gamma # [2258520.713033] gamma systemd-networkd[207]: /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-data-mesher> gamma # [2258520.713188] gamma systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> gamma # [2258520.713192] gamma systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> gamma # [2258520.713390] gamma systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> gamma # [2258520.713752] gamma systemd[1]: Started Network Management.
container-test-run-data-mesher> gamma # [2258520.740349] gamma systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> gamma # [2258520.740503] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> gamma # [2258520.740751] gamma systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> gamma # [2258520.810107] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [2258519.845493] beta systemd-journald[90]: Journal started
container-test-run-data-mesher> alpha # [2258519.855031] alpha systemd-journald[90]: Journal started
container-test-run-data-mesher> beta # [2258519.845555] beta systemd-journald[90]: Runtime Journal (/run/log/journal/5ec4006ff1dd4a4b81449cbf7e270dc0) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> alpha # [2258519.855092] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/21705187cdfe4ddd8f134ae627f8c0ce) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> beta # [2258519.855258] beta systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> alpha # [2258519.858574] alpha systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> beta # [2258519.868552] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> alpha # [2258519.869873] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> beta # [2258519.883354] beta systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> alpha # [2258519.882540] alpha systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [2258519.884417] beta systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> alpha # [2258519.883405] alpha systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [2258519.885138] beta systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> alpha # [2258519.884396] alpha systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [2258519.891593] beta systemd-journald[90]: Time spent on flushing to /var/log/journal/5ec4006ff1dd4a4b81449cbf7e270dc0 is 1.645ms for 7 entries.
container-test-run-data-mesher> alpha # [2258519.891263] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/21705187cdfe4ddd8f134ae627f8c0ce is 1.240ms for 7 entries.
container-test-run-data-mesher> beta # [2258519.891593] beta systemd-journald[90]: System Journal (/var/log/journal/5ec4006ff1dd4a4b81449cbf7e270dc0) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [2258519.908532] beta systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> alpha # [2258519.891263] alpha systemd-journald[90]: System Journal (/var/log/journal/21705187cdfe4ddd8f134ae627f8c0ce) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [2258519.909906] beta systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> alpha # [2258519.907031] alpha systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> beta # [2258519.910996] beta systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> alpha # [2258519.907267] alpha systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> beta # [2258519.911109] beta systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> alpha # [2258519.908354] alpha systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [2258519.911934] beta systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [2258519.908463] alpha systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> beta # [2258519.911980] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [2258519.909330] alpha systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> beta # [2258519.912884] beta systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> alpha # [2258519.909379] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [2258519.913548] beta systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [2258519.910537] alpha systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> beta # [2258519.913582] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> beta # [2258519.936406] beta systemd-tmpfiles[147]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [2258519.911339] alpha systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> beta # [2258519.936593] beta systemd-tmpfiles[147]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [2258519.936723] beta systemd-tmpfiles[147]: fchmod() of /var/log/journal/5ec4006ff1dd4a4b81449cbf7e270dc0 failed: Operation not permitted
container-test-run-data-mesher> beta # [2258519.936919] beta systemd-tmpfiles[147]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [2258519.938212] beta systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> beta # [2258519.939460] beta systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> beta # [2258519.940248] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> beta # [2258519.954211] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> beta # [2258519.960308] beta systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> beta # [2258519.961597] beta systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> beta # [2258519.972956] beta systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> beta # [2258520.021923] beta systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [2258520.022112] beta systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> beta # [2258520.022382] beta systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> beta # [2258520.023824] beta systemd[1]: Starting Network Management...
container-test-run-data-mesher> beta # [2258520.706082] beta systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [2258520.706170] beta systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [2258520.715547] beta systemd-networkd[207]: /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-data-mesher> beta # [2258520.715712] beta systemd-networkd[207]: /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-data-mesher> beta # [2258520.715868] beta systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> beta # [2258520.715874] beta systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> beta # [2258520.716050] beta systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [2258520.716509] beta systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [2258520.740499] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [2258520.740701] beta systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> beta # [2258520.741048] beta systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> beta # [2258520.790384] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> alpha # [2258519.911376] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [2258519.936838] alpha systemd-tmpfiles[142]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [2258519.937036] alpha systemd-tmpfiles[142]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [2258519.937172] alpha systemd-tmpfiles[142]: fchmod() of /var/log/journal/21705187cdfe4ddd8f134ae627f8c0ce failed: Operation not permitted
container-test-run-data-mesher> alpha # [2258519.937382] alpha systemd-tmpfiles[142]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [2258519.938727] alpha systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [2258519.939912] alpha systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> alpha # [2258519.940700] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [2258519.952406] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [2258519.963018] alpha systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [2258519.964039] alpha systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> alpha # [2258519.976363] alpha systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> alpha # [2258520.026664] alpha systemd[1]: Finished Firewall.
container-test-run-data-mesher> alpha # [2258520.026819] alpha systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> alpha # [2258520.027034] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [2258520.028207] alpha systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [2258520.692655] alpha systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [2258520.692742] alpha systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [2258520.702177] alpha systemd-networkd[207]: /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-data-mesher> alpha # [2258520.702346] alpha systemd-networkd[207]: /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-data-mesher> alpha # [2258520.702517] alpha systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> alpha # [2258520.702522] alpha systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> alpha # [2258520.702722] alpha systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [2258520.703113] alpha systemd[1]: Started Network Management.
container-test-run-data-mesher> alpha # [2258520.703183] alpha systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> alpha # [2258520.703530] alpha systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> alpha # [2258520.704967] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> alpha # [2258520.750536] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [2258521.020125] beta systemd-resolved[126]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [2258521.020137] beta systemd-resolved[126]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> beta # [2258521.020141] beta systemd-resolved[126]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> beta # [2258521.020176] beta systemd-resolved[126]: 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-data-mesher> beta # [2258521.041455] beta systemd-resolved[126]: Using system hostname 'beta'.
container-test-run-data-mesher> beta # [2258521.042855] beta systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> beta # [2258521.042987] beta systemd[1]: Reached target Network.
container-test-run-data-mesher> beta # [2258521.043108] beta systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> beta # [2258521.043195] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [2258521.043250] beta systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> beta # [2258521.043303] beta systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [2258521.043697] beta systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> beta # [2258521.043931] beta systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> beta # [2258521.044197] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> beta # [2258521.044253] beta systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [2258521.044344] beta systemd[1]: Reached target Basic System.
container-test-run-data-mesher> beta # [2258521.046548] beta systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> beta # [2258521.048065] beta systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> beta # [2258521.049555] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> beta # [2258521.051760] beta systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> beta # [2258521.102631] beta systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> gamma # [2258521.031976] gamma systemd-resolved[121]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [2258521.031989] gamma systemd-resolved[121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [2258521.031992] gamma systemd-resolved[121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [2258521.032039] gamma systemd-resolved[121]: 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-data-mesher> gamma # [2258521.053220] gamma systemd-resolved[121]: Using system hostname 'gamma'.
container-test-run-data-mesher> gamma # [2258521.054637] gamma systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [2258521.054771] gamma systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [2258521.054902] gamma systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [2258521.054996] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [2258521.055057] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> gamma # [2258521.055096] gamma systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> gamma # [2258521.055321] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> gamma # [2258521.055527] gamma systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> gamma # [2258521.055749] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [2258521.055801] gamma systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> gamma # [2258521.055879] gamma systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [2258521.085254] gamma systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [2258521.086700] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [2258521.088153] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> gamma # [2258521.090260] gamma systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> gamma # [2258521.109960] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [2258521.035750] alpha systemd-resolved[121]: Positive Trust Anchors:
container-test-run-data-mesher> alpha # [2258521.035762] alpha systemd-resolved[121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> alpha # [2258521.035766] alpha systemd-resolved[121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> alpha # [2258521.035802] alpha systemd-resolved[121]: 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-data-mesher> alpha # [2258521.056908] alpha systemd-resolved[121]: Using system hostname 'alpha'.
container-test-run-data-mesher> alpha # [2258521.058288] alpha systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> alpha # [2258521.058425] alpha systemd[1]: Reached target Network.
container-test-run-data-mesher> alpha # [2258521.058552] alpha systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> alpha # [2258521.058646] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [2258521.058712] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [2258521.058749] alpha systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [2258521.058966] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> alpha # [2258521.059166] alpha systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [2258521.059376] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> alpha # [2258521.059422] alpha systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> alpha # [2258521.059515] alpha systemd[1]: Reached target Basic System.
container-test-run-data-mesher> alpha # [2258521.085317] alpha systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> alpha # [2258521.086892] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> alpha # [2258521.088346] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> alpha # [2258521.090565] alpha systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [2258521.108762] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [2258521.309331] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [2258521.309420] alpha systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [2258521.309750] alpha nsncd[214]: Sep 30 10:49:01.295 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [2258521.309540] alpha systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> alpha # [2258521.311788] alpha systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [2258521.313451] alpha systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> alpha # [2258521.364793] alpha systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> alpha # [2258521.366229] alpha systemd[1]: Started Console Getty.
container-test-run-data-mesher> alpha # [2258521.366291] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> alpha # [2258521.366315] alpha systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> alpha # [2258521.475546] alpha dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> alpha # [2258521.477251] alpha dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> alpha # [2258521.477251] alpha dbus-broker-launch[215]: Invalid user-name in /nix/store/j492sriin4z5mjqly2bf7a7rp9pjfcr2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> alpha # [2258521.477972] alpha systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [2258521.486579] alpha dbus-broker-launch[215]: Ready
container-test-run-data-mesher> beta # [2258521.336766] beta nsncd[214]: Sep 30 10:49:01.322 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> beta # [2258521.336808] beta systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> beta # [2258521.336910] beta systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> beta # [2258521.337021] beta systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> beta # [2258521.356547] beta systemd[1]: Starting User Login Management...
container-test-run-data-mesher> beta # [2258521.357869] beta systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> beta # [2258521.368504] beta systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> beta # [2258521.370789] beta systemd[1]: Started Console Getty.
container-test-run-data-mesher> beta # [2258521.370868] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> beta # [2258521.370909] beta systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> beta # [2258521.517967] beta dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [2258521.519653] beta dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> beta # [2258521.519711] beta dbus-broker-launch[215]: Invalid user-name in /nix/store/j492sriin4z5mjqly2bf7a7rp9pjfcr2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [2258521.520157] beta systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> beta # [2258521.529888] beta dbus-broker-launch[215]: Ready
container-test-run-data-mesher> gamma # [2258521.311986] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> gamma # [2258521.312125] gamma systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> gamma # [2258521.312249] gamma systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> gamma # [2258521.312544] gamma nsncd[214]: Sep 30 10:49:01.298 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> gamma # [2258521.314451] gamma systemd[1]: Starting User Login Management...
container-test-run-data-mesher> gamma # [2258521.315860] gamma systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [2258521.364575] gamma systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> gamma # [2258521.365883] gamma systemd[1]: Started Console Getty.
container-test-run-data-mesher> gamma # [2258521.365934] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> gamma # [2258521.365957] gamma systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [2258521.487005] gamma dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> gamma # [2258521.488603] gamma dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> gamma # [2258521.488603] gamma dbus-broker-launch[215]: Invalid user-name in /nix/store/j492sriin4z5mjqly2bf7a7rp9pjfcr2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> gamma # [2258521.489046] gamma systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> gamma # [2258521.497942] gamma dbus-broker-launch[215]: Ready
container-test-run-data-mesher> gamma # [2258521.931280] gamma data-mesher[212]: time=2026-09-30T10:49:01.917Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> gamma # [2258521.932415] gamma data-mesher[212]: time=2026-09-30T10:49:01.918Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe: [/dns/alpha.clan/tcp/7946]} {12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> gamma # [2258521.932415] gamma data-mesher[212]: time=2026-09-30T10:49:01.918Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> gamma # [2258521.984079] gamma data-mesher[212]: time=2026-09-30T10:49:01.969Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> gamma # [2258521.984186] gamma data-mesher[212]: time=2026-09-30T10:49:01.970Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> gamma # [2258521.984147] gamma systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> gamma # [2258521.988358] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="libp2p host created" peer_id=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu 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]"
container-test-run-data-mesher> gamma # [2258521.988406] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> gamma # [2258521.988406] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> gamma # [2258521.988406] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> gamma # [2258521.988406] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="starting server"
container-test-run-data-mesher> gamma # [2258521.988509] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> gamma # [2258521.988595] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> gamma # [2258521.988624] gamma data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [2258521.929173] alpha data-mesher[212]: time=2026-09-30T10:49:01.914Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> alpha # [2258521.930441] alpha data-mesher[212]: time=2026-09-30T10:49:01.916Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe: [/dns/alpha.clan/tcp/7946]} {12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> alpha # [2258521.930441] alpha data-mesher[212]: time=2026-09-30T10:49:01.916Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> alpha # [2258521.952178] alpha systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha # [2258521.983994] alpha data-mesher[212]: time=2026-09-30T10:49:01.969Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> alpha # [2258521.984124] alpha data-mesher[212]: time=2026-09-30T10:49:01.969Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> alpha # [2258521.988210] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="libp2p host created" peer_id=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe 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]"
container-test-run-data-mesher> alpha # [2258521.988251] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> alpha # [2258521.988251] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> alpha # [2258521.988251] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> alpha # [2258521.988251] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [2258521.988359] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [2258521.988480] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [2258521.988503] alpha data-mesher[212]: time=2026-09-30T10:49:01.974Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [2258522.001263] alpha data-mesher[212]: time=2026-09-30T10:49:01.987Z level=INFO msg="peer connected" peer_id=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> beta # [2258521.955706] beta data-mesher[212]: time=2026-09-30T10:49:01.941Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> beta # [2258521.957060] beta data-mesher[212]: time=2026-09-30T10:49:01.942Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe: [/dns/alpha.clan/tcp/7946]} {12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> beta # [2258521.957060] beta data-mesher[212]: time=2026-09-30T10:49:01.942Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> beta # [2258521.984330] beta data-mesher[212]: time=2026-09-30T10:49:01.970Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [2258521.984549] beta data-mesher[212]: time=2026-09-30T10:49:01.970Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [2258521.992399] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="libp2p host created" peer_id=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy 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]"
container-test-run-data-mesher> beta # [2258521.992477] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> beta # [2258521.992477] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> beta # [2258521.992477] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> beta # [2258521.992477] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="starting server"
container-test-run-data-mesher> beta # [2258521.992661] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> beta # [2258521.992709] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> beta # [2258521.992768] beta data-mesher[212]: time=2026-09-30T10:49:01.978Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> beta # [2258521.999791] beta data-mesher[212]: time=2026-09-30T10:49:01.985Z level=INFO msg="peer connected" peer_id=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [2258522.048171] beta systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [2258522.219344] beta systemd-logind[231]: New seat seat0.
container-test-run-data-mesher> beta # [2258522.219530] beta systemd[1]: Started User Login Management.
container-test-run-data-mesher> beta # [2258522.244789] beta systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> beta # [2258522.258368] beta systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> beta # [2258522.258480] beta systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> gamma # [2258522.202729] gamma systemd-logind[231]: New seat seat0.
container-test-run-data-mesher> gamma # [2258522.202969] gamma systemd[1]: Started User Login Management.
container-test-run-data-mesher> gamma # [2258522.205016] gamma systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [2258522.258636] gamma systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> gamma # [2258522.258770] gamma systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> alpha # [2258522.207127] alpha systemd-logind[231]: New seat seat0.
container-test-run-data-mesher> alpha # [2258522.207377] alpha systemd[1]: Started User Login Management.
container-test-run-data-mesher> alpha # [2258522.209332] alpha systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> alpha # [2258522.255730] alpha systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> alpha # [2258522.256021] alpha systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> beta # [2258522.782135] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> beta # [2258522.784112] beta systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> beta # [2258523.013219] beta data-mesher[212]: time=2026-09-30T10:49:02.999Z level=INFO msg="peer connected" peer_id=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe remote_addr=/ip6zone/eth1/ip6/fe80::80a3:29ff:fee8:a695/tcp/7946
container-test-run-data-mesher> beta # [2258523.020100] beta data-mesher[212]: time=2026-09-30T10:49:03.005Z level=INFO msg="peer connected" peer_id=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu remote_addr=/ip6zone/eth1/ip6/fe80::1807:e0ff:feb2:45fc/tcp/7946
container-test-run-data-mesher> gamma # [2258522.787342] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [2258522.789159] gamma systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [2258523.018181] gamma data-mesher[212]: time=2026-09-30T10:49:03.003Z level=INFO msg="peer connected" peer_id=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy remote_addr=/ip6/2001:db8:1::2/tcp/7946
container-test-run-data-mesher> gamma # [2258523.027130] gamma data-mesher[212]: time=2026-09-30T10:49:03.012Z level=INFO msg="peer connected" peer_id=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe remote_addr=/ip6/2001:db8:1::1/tcp/7946
container-test-run-data-mesher> alpha # [2258522.786519] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> alpha # [2258522.788312] alpha systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> alpha # [2258523.012410] alpha data-mesher[212]: time=2026-09-30T10:49:02.998Z level=INFO msg="peer connected" peer_id=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy remote_addr=/ip6/2001:db8:1::2/tcp/7946
container-test-run-data-mesher> alpha # [2258523.027756] alpha data-mesher[212]: time=2026-09-30T10:49:03.013Z level=INFO msg="peer connected" peer_id=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu remote_addr=/ip6/2001:db8:1::3/tcp/7946
container-test-run-data-mesher> alpha: still waiting for container 'alpha' to reach ready state...
container-test-run-data-mesher> alpha: (finished: waiting for unit data-mesher.service, in 13.18 seconds)
container-test-run-data-mesher> beta: waiting for unit data-mesher.service
container-test-run-data-mesher> beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)
container-test-run-data-mesher> gamma: waiting for unit data-mesher.service
container-test-run-data-mesher> gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'hello world' > /tmp/test_file
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)
container-test-run-data-mesher> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-data-mesher>     File "/nix/store/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-data-mesher>     File "/nix/store/drwj5qfqx9axxgf0jvxlavglj43fr2kh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> beta # [2258531.989249] beta data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="received state sync from peer" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258531.989249] beta data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258531.993078] beta data-mesher[212]: time=2026-09-30T10:49:11.978Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> beta # [2258531.993173] beta data-mesher[212]: time=2026-09-30T10:49:11.978Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258531.993796] beta data-mesher[212]: time=2026-09-30T10:49:11.979Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258531.993796] beta data-mesher[212]: time=2026-09-30T10:49:11.979Z level=INFO msg="state exchange complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258531.993912] beta data-mesher[212]: time=2026-09-30T10:49:11.979Z level=INFO msg="server started"
container-test-run-data-mesher> beta # [2258531.994027] beta data-mesher[212]: time=2026-09-30T10:49:11.979Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> beta # [2258531.994131] beta systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> beta # [2258531.994657] beta systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [2258531.995023] beta systemd[1]: Startup finished in 12.569s.
container-test-run-data-mesher> gamma # [2258531.989672] gamma data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [2258531.989672] gamma data-mesher[212]: time=2026-09-30T10:49:11.975Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> gamma # [2258531.990434] gamma data-mesher[212]: time=2026-09-30T10:49:11.976Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258531.990434] gamma data-mesher[212]: time=2026-09-30T10:49:11.976Z level=INFO msg="state exchange complete" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> gamma # [2258531.990434] gamma data-mesher[212]: time=2026-09-30T10:49:11.976Z level=INFO msg="server started"
container-test-run-data-mesher> gamma # [2258531.990599] gamma data-mesher[212]: time=2026-09-30T10:49:11.976Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> gamma # [2258531.990579] gamma systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> gamma # [2258531.991106] gamma systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> gamma # [2258531.991436] gamma systemd[1]: Startup finished in 12.556s.
container-test-run-data-mesher> gamma # [2258531.993612] gamma data-mesher[212]: time=2026-09-30T10:49:11.979Z level=INFO msg="received state sync from peer" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258531.993612] gamma data-mesher[212]: time=2026-09-30T10:49:11.979Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> alpha # [2258531.988502] alpha data-mesher[212]: time=2026-09-30T10:49:11.974Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> alpha # [2258531.988502] alpha data-mesher[212]: time=2026-09-30T10:49:11.974Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258531.989511] alpha data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> alpha # [2258531.989511] alpha data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="state exchange complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258531.989645] alpha data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [2258531.989737] alpha data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [2258531.989897] alpha systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [2258531.990434] alpha systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> alpha # [2258531.990578] alpha data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="received state sync from peer" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258531.990578] alpha data-mesher[212]: time=2026-09-30T10:49:11.975Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258531.990829] alpha systemd[1]: Startup finished in 12.557s.
container-test-run-data-mesher> alpha # [2258532.103676] alpha data-mesher[212]: time=2026-09-30T10:49:12.089Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta # [2258536.991973] beta data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="received state sync from peer" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258536.991973] beta data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258536.994657] beta data-mesher[212]: time=2026-09-30T10:49:16.980Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [2258536.994737] beta data-mesher[212]: time=2026-09-30T10:49:16.980Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258536.995538] beta data-mesher[212]: time=2026-09-30T10:49:16.981Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258536.995538] beta data-mesher[212]: time=2026-09-30T10:49:16.981Z level=INFO msg="state exchange complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258536.995655] beta data-mesher[212]: time=2026-09-30T10:49:16.981Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [2258536.991390] gamma data-mesher[212]: time=2026-09-30T10:49:16.977Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [2258536.991390] gamma data-mesher[212]: time=2026-09-30T10:49:16.977Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="received state sync from peer" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.977Z level=DEBUG msg="new file detected" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe name=test_file name=test_file
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-30 10:49:12.086 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.978Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.978Z level=INFO msg="state exchange complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> gamma # [2258536.992258] gamma data-mesher[212]: time=2026-09-30T10:49:16.978Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [2258536.995224] gamma data-mesher[212]: time=2026-09-30T10:49:16.981Z level=INFO msg="received state sync from peer" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258536.995224] gamma data-mesher[212]: time=2026-09-30T10:49:16.981Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258536.997293] gamma data-mesher[212]: time=2026-09-30T10:49:16.983Z level=INFO msg="download complete" name=test_file signed_at="2026-09-30 10:49:12.086 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe written=true elapsed=5.499556ms
container-test-run-data-mesher> alpha # [2258536.990757] alpha data-mesher[212]: time=2026-09-30T10:49:16.976Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [2258536.990757] alpha data-mesher[212]: time=2026-09-30T10:49:16.976Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> alpha # [2258536.991815] alpha data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258536.991815] alpha data-mesher[212]: time=2026-09-30T10:49:16.977Z level=INFO msg="state exchange complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> alpha # [2258536.991948] alpha data-mesher[212]: time=2026-09-30T10:49:16.977Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [2258536.992428] alpha data-mesher[212]: time=2026-09-30T10:49:16.978Z level=INFO msg="received file request" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_file
container-test-run-data-mesher> alpha # [2258536.994932] alpha data-mesher[212]: time=2026-09-30T10:49:16.980Z level=INFO msg="file transfer complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_file
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 10.09 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> beta # [2258541.992990] beta data-mesher[212]: time=2026-09-30T10:49:21.978Z level=INFO msg="received state sync from peer" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258541.992990] beta data-mesher[212]: time=2026-09-30T10:49:21.978Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258541.992990] beta data-mesher[212]: time=2026-09-30T10:49:21.978Z level=DEBUG msg="new file detected" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe name=test_file name=test_file
container-test-run-data-mesher> beta # [2258541.993918] beta data-mesher[212]: time=2026-09-30T10:49:21.978Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> beta # [2258541.993918] beta data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-30 10:49:12.086 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258541.993918] beta data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="received state sync from peer" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258541.993918] beta data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258541.993918] beta data-mesher[212]: time=2026-09-30T10:49:21.979Z level=DEBUG msg="new file detected" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu name=test_file name=test_file
container-test-run-data-mesher> beta # [2258541.996534] beta data-mesher[212]: time=2026-09-30T10:49:21.982Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [2258541.996635] beta data-mesher[212]: time=2026-09-30T10:49:21.982Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> beta # [2258541.997511] beta data-mesher[212]: time=2026-09-30T10:49:21.983Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258541.997577] beta data-mesher[212]: time=2026-09-30T10:49:21.983Z level=DEBUG msg="new file detected" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe name=test_file name=test_file
container-test-run-data-mesher> beta # [2258541.997577] beta data-mesher[212]: time=2026-09-30T10:49:21.983Z level=INFO msg="state exchange complete" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> beta # [2258541.997683] beta data-mesher[212]: time=2026-09-30T10:49:21.983Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [2258542.008757] beta data-mesher[212]: time=2026-09-30T10:49:21.994Z level=INFO msg="download complete" name=test_file signed_at="2026-09-30 10:49:12.086 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe written=true elapsed=15.567575ms
container-test-run-data-mesher> beta # [2258542.247480] beta data-mesher[212]: time=2026-09-30T10:49:22.233Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)
container-test-run-data-mesher> alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma # [2258541.992952] gamma data-mesher[212]: time=2026-09-30T10:49:21.978Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [2258541.992952] gamma data-mesher[212]: time=2026-09-30T10:49:21.978Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> gamma # [2258541.994068] gamma data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258541.994068] gamma data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="state exchange complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> gamma # [2258541.994212] gamma data-mesher[212]: time=2026-09-30T10:49:21.979Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [2258541.992054] alpha data-mesher[212]: time=2026-09-30T10:49:21.977Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [2258541.992054] alpha data-mesher[212]: time=2026-09-30T10:49:21.977Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258541.993299] alpha data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> alpha # [2258541.993299] alpha data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="state exchange complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258541.993434] alpha data-mesher[212]: time=2026-09-30T10:49:21.979Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [2258541.993776] alpha data-mesher[212]: time=2026-09-30T10:49:21.979Z level=INFO msg="received file request" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_file
container-test-run-data-mesher> alpha # [2258541.996170] alpha data-mesher[212]: time=2026-09-30T10:49:21.981Z level=INFO msg="file transfer complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_file
container-test-run-data-mesher> alpha # [2258541.997129] alpha data-mesher[212]: time=2026-09-30T10:49:21.982Z level=INFO msg="received state sync from peer" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> alpha # [2258541.997210] alpha data-mesher[212]: time=2026-09-30T10:49:21.982Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> beta # [2258546.994892] beta data-mesher[212]: time=2026-09-30T10:49:26.980Z level=INFO msg="received state sync from peer" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258546.994892] beta data-mesher[212]: time=2026-09-30T10:49:26.980Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258546.997963] beta data-mesher[212]: time=2026-09-30T10:49:26.983Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [2258546.998073] beta data-mesher[212]: time=2026-09-30T10:49:26.983Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258547.001024] beta data-mesher[212]: time=2026-09-30T10:49:26.986Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258547.001024] beta data-mesher[212]: time=2026-09-30T10:49:26.986Z level=INFO msg="state exchange complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258547.001197] beta data-mesher[212]: time=2026-09-30T10:49:26.986Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.05 seconds)
container-test-run-data-mesher> beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma # [2258546.994721] gamma data-mesher[212]: time=2026-09-30T10:49:26.980Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [2258546.994721] gamma data-mesher[212]: time=2026-09-30T10:49:26.980Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> gamma # [2258546.995911] gamma data-mesher[212]: time=2026-09-30T10:49:26.981Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258546.995911] gamma data-mesher[212]: time=2026-09-30T10:49:26.981Z level=INFO msg="state exchange complete" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> gamma # [2258546.996145] gamma data-mesher[212]: time=2026-09-30T10:49:26.981Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [2258546.998744] gamma data-mesher[212]: time=2026-09-30T10:49:26.984Z level=INFO msg="received state sync from peer" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258546.998744] gamma data-mesher[212]: time=2026-09-30T10:49:26.984Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258547.000749] gamma data-mesher[212]: time=2026-09-30T10:49:26.986Z level=DEBUG msg="imported tombstone" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy name=test_file written=true
container-test-run-data-mesher> beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> alpha: must succeed: cat /nix/store/q6fpanjgvvvfycv4s7c3bjs50wmkb4zq-per-machine-alpha-data-mesher-node-identity_identity.pub
container-test-run-data-mesher> alpha: (finished: must succeed: cat /nix/store/q6fpanjgvvvfycv4s7c3bjs50wmkb4zq-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file
container-test-run-data-mesher> alpha # [2258546.993974] alpha data-mesher[212]: time=2026-09-30T10:49:26.979Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [2258546.993974] alpha data-mesher[212]: time=2026-09-30T10:49:26.979Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258546.995188] alpha data-mesher[212]: time=2026-09-30T10:49:26.980Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> alpha # [2258546.995420] alpha data-mesher[212]: time=2026-09-30T10:49:26.981Z level=INFO msg="received state sync from peer" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258546.995491] alpha data-mesher[212]: time=2026-09-30T10:49:26.981Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258546.997682] alpha data-mesher[212]: time=2026-09-30T10:49:26.983Z level=DEBUG msg="imported tombstone" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy name=test_file written=true
container-test-run-data-mesher> alpha # [2258546.997682] alpha data-mesher[212]: time=2026-09-30T10:49:26.983Z level=INFO msg="state exchange complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258546.997917] alpha data-mesher[212]: time=2026-09-30T10:49:26.983Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> alpha # [2258547.368204] alpha data-mesher[212]: time=2026-09-30T10:49:27.354Z level=INFO msg=http_request uri=/files/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E status=204
container-test-run-data-mesher> beta # [2258551.997440] beta data-mesher[212]: time=2026-09-30T10:49:31.983Z level=INFO msg="received state sync from peer" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258551.997440] beta data-mesher[212]: time=2026-09-30T10:49:31.983Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258551.999398] beta data-mesher[212]: time=2026-09-30T10:49:31.985Z level=INFO msg="received state sync from peer" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258551.999398] beta data-mesher[212]: time=2026-09-30T10:49:31.985Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258552.000056] beta data-mesher[212]: time=2026-09-30T10:49:31.985Z level=DEBUG msg="new file detected" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe name=test_file name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> beta # [2258552.000207] beta data-mesher[212]: time=2026-09-30T10:49:31.985Z level=INFO msg="scheduling file download" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> beta # [2258552.000349] beta data-mesher[212]: time=2026-09-30T10:49:31.986Z level=INFO msg="downloading file" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E signed_at="2026-09-30 10:49:27.351 +0000 UTC" signed_by="ezWaNQd+g/WxM0omyTDX7R+ieGqp7/tKxZHgoo1ZU+E=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> beta # [2258552.001181] beta data-mesher[212]: time=2026-09-30T10:49:31.986Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [2258552.001260] beta data-mesher[212]: time=2026-09-30T10:49:31.987Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258552.002019] beta data-mesher[212]: time=2026-09-30T10:49:31.987Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258552.002139] beta data-mesher[212]: time=2026-09-30T10:49:31.987Z level=INFO msg="state exchange complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258552.002139] beta data-mesher[212]: time=2026-09-30T10:49:31.987Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [2258552.003888] beta data-mesher[212]: time=2026-09-30T10:49:31.989Z level=INFO msg="download complete" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E signed_at="2026-09-30 10:49:27.351 +0000 UTC" signed_by="ezWaNQd+g/WxM0omyTDX7R+ieGqp7/tKxZHgoo1ZU+E=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe written=true elapsed=3.62841ms
container-test-run-data-mesher> gamma # [2258551.996601] gamma data-mesher[212]: time=2026-09-30T10:49:31.982Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [2258551.997379] gamma data-mesher[212]: time=2026-09-30T10:49:31.982Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> gamma # [2258551.997830] gamma data-mesher[212]: time=2026-09-30T10:49:31.983Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258551.997911] gamma data-mesher[212]: time=2026-09-30T10:49:31.983Z level=INFO msg="state exchange complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> gamma # [2258551.997971] gamma data-mesher[212]: time=2026-09-30T10:49:31.983Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [2258552.001723] gamma data-mesher[212]: time=2026-09-30T10:49:31.987Z level=INFO msg="received state sync from peer" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258552.001799] gamma data-mesher[212]: time=2026-09-30T10:49:31.987Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> alpha # [2258551.998647] alpha data-mesher[212]: time=2026-09-30T10:49:31.984Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [2258551.998647] alpha data-mesher[212]: time=2026-09-30T10:49:31.984Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258552.000409] alpha data-mesher[212]: time=2026-09-30T10:49:31.986Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> alpha # [2258552.000696] alpha data-mesher[212]: time=2026-09-30T10:49:31.986Z level=INFO msg="state exchange complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy timeout=5s
container-test-run-data-mesher> alpha # [2258552.000696] alpha data-mesher[212]: time=2026-09-30T10:49:31.986Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [2258552.000833] alpha data-mesher[212]: time=2026-09-30T10:49:31.986Z level=INFO msg="received file request" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> alpha # [2258552.002322] alpha data-mesher[212]: time=2026-09-30T10:49:31.988Z level=INFO msg="file transfer complete" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E, in 5.05 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> beta # [2258557.003233] beta data-mesher[212]: time=2026-09-30T10:49:36.989Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [2258557.003233] beta data-mesher[212]: time=2026-09-30T10:49:36.989Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258557.004832] beta data-mesher[212]: time=2026-09-30T10:49:36.990Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> beta # [2258557.004957] beta data-mesher[212]: time=2026-09-30T10:49:36.990Z level=INFO msg="state exchange complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> beta # [2258557.004957] beta data-mesher[212]: time=2026-09-30T10:49:36.990Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [2258556.998462] gamma data-mesher[212]: time=2026-09-30T10:49:36.984Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [2258556.999176] gamma data-mesher[212]: time=2026-09-30T10:49:36.984Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> gamma # [2258556.999732] gamma data-mesher[212]: time=2026-09-30T10:49:36.985Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258557.000429] gamma data-mesher[212]: time=2026-09-30T10:49:36.986Z level=DEBUG msg="new file detected" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe name=test_file name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> gamma # [2258557.000507] gamma data-mesher[212]: time=2026-09-30T10:49:36.986Z level=INFO msg="state exchange complete" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe timeout=5s
container-test-run-data-mesher> gamma # [2258557.000507] gamma data-mesher[212]: time=2026-09-30T10:49:36.986Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [2258557.000627] gamma data-mesher[212]: time=2026-09-30T10:49:36.986Z level=INFO msg="scheduling file download" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> gamma # [2258557.000627] gamma data-mesher[212]: time=2026-09-30T10:49:36.986Z level=INFO msg="downloading file" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E signed_at="2026-09-30 10:49:27.351 +0000 UTC" signed_by="ezWaNQd+g/WxM0omyTDX7R+ieGqp7/tKxZHgoo1ZU+E=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258557.001368] gamma data-mesher[212]: time=2026-09-30T10:49:36.987Z level=INFO msg="received state sync from peer" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258557.001368] gamma data-mesher[212]: time=2026-09-30T10:49:36.987Z level=INFO msg="merging remote state" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe
container-test-run-data-mesher> gamma # [2258557.002024] gamma data-mesher[212]: time=2026-09-30T10:49:36.987Z level=DEBUG msg="new file detected" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe name=test_file name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> gamma # [2258557.003850] gamma data-mesher[212]: time=2026-09-30T10:49:36.989Z level=INFO msg="received state sync from peer" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258557.003850] gamma data-mesher[212]: time=2026-09-30T10:49:36.989Z level=INFO msg="merging remote state" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy
container-test-run-data-mesher> gamma # [2258557.004517] gamma data-mesher[212]: time=2026-09-30T10:49:36.990Z level=DEBUG msg="new file detected" peer=12D3KooWQ4CcXhy1JWT5tuk7cMcwhkihqFeSbM8LnU53sfMpkHSy name=test_file name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> gamma # [2258557.005516] gamma data-mesher[212]: time=2026-09-30T10:49:36.991Z level=INFO msg="download complete" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E signed_at="2026-09-30 10:49:27.351 +0000 UTC" signed_by="ezWaNQd+g/WxM0omyTDX7R+ieGqp7/tKxZHgoo1ZU+E=" peer=12D3KooWJ7KipY8CsBGjAhAxoCJU29DgRdf6r3FL5j2Z5wMzyhXe written=true elapsed=4.933187ms
container-test-run-data-mesher> alpha # [2258556.999275] alpha data-mesher[212]: time=2026-09-30T10:49:36.984Z level=INFO msg="received state sync from peer" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258556.999275] alpha data-mesher[212]: time=2026-09-30T10:49:36.985Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258557.000699] alpha data-mesher[212]: time=2026-09-30T10:49:36.986Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [2258557.000788] alpha data-mesher[212]: time=2026-09-30T10:49:36.986Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> alpha # [2258557.001140] alpha data-mesher[212]: time=2026-09-30T10:49:36.986Z level=INFO msg="received file request" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> alpha # [2258557.002452] alpha data-mesher[212]: time=2026-09-30T10:49:36.988Z level=INFO msg="merging remote state" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu
container-test-run-data-mesher> alpha # [2258557.002591] alpha data-mesher[212]: time=2026-09-30T10:49:36.988Z level=INFO msg="state exchange complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu timeout=5s
container-test-run-data-mesher> alpha # [2258557.002591] alpha data-mesher[212]: time=2026-09-30T10:49:36.988Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [2258557.004038] alpha data-mesher[212]: time=2026-09-30T10:49:36.989Z level=INFO msg="file transfer complete" peer=12D3KooWNqn6nTzBv1hT6Mw2ziSWNiEnDc4KpgEeNGxZjgvLhehu network="RIjyaKmGMAjvmZGomkyWdmgDRwi96PNNo6Rqgkrra+4=" name=test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E, in 5.05 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)
container-test-run-data-mesher> alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub
container-test-run-data-mesher> Error: failed to update file: 403 Forbidden, signer ezWaNQd+g/WxM0omyTDX7R+ieGqp7/tKxZHgoo1ZU+E= is not authorized for this file test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E
container-test-run-data-mesher> alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/ezWaNQd-g_WxM0omyTDX7R-ieGqp7_tKxZHgoo1ZU-E' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/drlmynf1lp1339y8k68fd911hz8nl6gc-shared-data-mesher-network_network.pub, in 0.03 seconds)
container-test-run-data-mesher> (finished: run the VM test script, in 38.69 seconds)
container-test-run-data-mesher> test script finished in 38.82s
container-test-run-data-mesher> cleanup
container-test-run-data-mesher> kill NspawnMachine (pid 53)
container-test-run-data-mesher> kill NspawnMachine (pid 54)
container-test-run-data-mesher> Container alpha terminated by signal KILL.
container-test-run-data-mesher> kill NspawnMachine (pid 55)
container-test-run-data-mesher> Container beta terminated by signal KILL.
container-test-run-data-mesher> Container gamma terminated by signal KILL.
container-test-run-data-mesher> (finished: cleanup, in 0.49 seconds)