these 55 derivations will be built:
  /nix/store/1za9j5r9yq44yzrhwbw84rbslfwp7y9c-vars-check-data-mesher.drv
  /nix/store/lmzyymwzxap2qwlwsw328k9kiqd2wcva-system-path.drv
  /nix/store/qfr6cvpcrrgn4mxvz6w480zs48flvc6g-dbus-1.drv
  /nix/store/af72bja4yb8kncarwmair860d6khypdn-X-Restart-Triggers-dbus-broker.drv
  /nix/store/kxga0cybvrshqwk8yk4khc8aqjmx9asf-unit-dbus-broker.service.drv
  /nix/store/2a4zkq3as2wc19qa0422mkq5pnpgm1zd-user-units.drv
  /nix/store/3rs56j7083hacz98706j6mhm2qi0q8yp-dm.toml.drv
  /nix/store/dmhkizs93876lr5xhxvfg8gxglg4cbyx-etc-hostname.drv
  /nix/store/g8q3xdhq3r2zz3m345m114xb7wbv23r7-unit-dbus-broker.service.drv
  /nix/store/jr3rprkfzy4zz9rh30g5pkncgpdvxh52-firewall-start.drv
  /nix/store/3flb4ly4vvzz23a4nhmqw47m2w728zdk-firewall-reload.drv
  /nix/store/jasqcrs9k4j2xckjgmfap73jx7cpakcr-unit-firewall.service.drv
  /nix/store/g5gvlgd3msh0xlc0lj5gxf75n85dw6js-X-Restart-Triggers-data-mesher.drv
  /nix/store/x7aazpndccqw92iv0aib8vlzjqmz9nhc-unit-data-mesher.service.drv
  /nix/store/dy8f61x9aj9rwpq0ij8rmnaw5q9q3zhn-system-units.drv
  /nix/store/8gbs8ccx9mz60xc7d8gdap3xnikh6sia-string-hosts.drv
  /nix/store/q42dfd84fnlxqv6jqjwwvz3888xb2k83-extra-hosts.drv
  /nix/store/ws3h0b3alldwvfv9i39wrbwl75s1r52v-hosts.drv
  /nix/store/ddnw0l2cnlg2gi306fxklhnrkmaqxabd-etc.drv
  /nix/store/17n07jv6jvam4as7mcmvdijgd2c0yc96-users-groups.json.drv
  /nix/store/gmkwwyyd54kzhwzvl6wsinpkbby3zj7i-decrypt-age-secrets.drv
  /nix/store/fq6jyim33djp4qj9saisl4288hxsjpxx-dry-activate.drv
  /nix/store/vpbwc4nlg375s3vrnfph02b0xqn6fmd4-activate.drv
  /nix/store/0cid6a069zdi903rl60ykyryp0rvs7sv-nixos-system-beta-test.drv
  /nix/store/092r3bcddzh8ynqb8mr21s447kbdkb2i-run-beta-nspawn.drv
  /nix/store/0g806imy3syzv41jamsrvgx34x0h26dn-etc-hostname.drv
  /nix/store/353wlbspmn3y1k0265ldh7ijzvwymsv2-test-script.drv
  /nix/store/r72wf1v5mcl2qw29ip031gy0zqhf02ln-dm.toml.drv
  /nix/store/iaaw79aqb7y7yj1aa1mla11mg2hwaj2g-X-Restart-Triggers-data-mesher.drv
  /nix/store/fjzc8bc4g857h9y43yr5a6pvrcgcys5n-unit-data-mesher.service.drv
  /nix/store/3grdmn57di7np951s8scisqdv033xikc-system-units.drv
  /nix/store/3irl4xmn9l3ldkv4bqz2f79bvj2a6pmb-dm.toml.drv
  /nix/store/rdfb4fgwbcnv6f4gn92f7qvf9w7di132-string-hosts.drv
  /nix/store/9n5zyyq1prf8qxknf9i7g8h7qllssd44-hosts.drv
  /nix/store/x76a7g5dm24l1aznv4psfw6i8ih04bpd-etc-hostname.drv
  /nix/store/7wi0981axipciiihfanzvcyx978dyz0j-X-Restart-Triggers-data-mesher.drv
  /nix/store/dbrhbxc2dbch59x4pvfpjvnjj3dwmzjw-unit-data-mesher.service.drv
  /nix/store/xl06sz5bk0hrlx0j71i53dlgd4yz6n5k-system-units.drv
  /nix/store/lh6awj1bvm5rhygplnma3yi9aywdpgyq-etc.drv
  /nix/store/nidril14p2gv058jli8bi4cx3dzzn316-decrypt-age-secrets.drv
  /nix/store/rjc36af27m2xc2n29662rdqvfrhzkaxz-activate.drv
  /nix/store/sj045h6ra5izps67lwccnhdb3ldl420m-dry-activate.drv
  /nix/store/49hcic6ajc0ccc89gjcg8id317l74ka0-nixos-system-alpha-test.drv
  /nix/store/gc3dzapn6kc264w7qpz6902a3c4fcwcw-string-hosts.drv
  /nix/store/4kznmlsifwhp57kdigvw91klc85rwi6j-hosts.drv
  /nix/store/vqk1n69bvywnl5qvqvmnp16wc4psw8b7-decrypt-age-secrets.drv
  /nix/store/7gjdj504ic5scvcq63lnvwf3cqnfbzrz-dry-activate.drv
  /nix/store/hah4ls58vfvv33wa9wh5rdbkv5d0kdx6-etc.drv
  /nix/store/x21v3hmqb7waxdp5hf4qd82i7v3h1987-activate.drv
  /nix/store/cmcalrphr5ndp4ghja7vn97rrxfxaffh-nixos-system-gamma-test.drv
  /nix/store/fgpwsvq97nzab77bzgsc23ndw75hq69y-run-gamma-nspawn.drv
  /nix/store/im7m23qqr5hrsr7m7w7k1l0c07f4p9ki-run-alpha-nspawn.drv
  /nix/store/lld001di2x7nrfrjnjcbz2bp1g4bncl7-driverConfiguration.json.drv
  /nix/store/fvi6nbw9s3xda2d7jvwjdyvv72dqv9ac-nixos-test-driver-data-mesher.drv
  /nix/store/vxpq33yvk0rdn91qzmmvyvnxndpa5157-container-test-run-data-mesher.drv
building '/nix/store/353wlbspmn3y1k0265ldh7ijzvwymsv2-test-script.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/lmzyymwzxap2qwlwsw328k9kiqd2wcva-system-path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0g806imy3syzv41jamsrvgx34x0h26dn-etc-hostname.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/dmhkizs93876lr5xhxvfg8gxglg4cbyx-etc-hostname.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/x76a7g5dm24l1aznv4psfw6i8ih04bpd-etc-hostname.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/q42dfd84fnlxqv6jqjwwvz3888xb2k83-extra-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/8gbs8ccx9mz60xc7d8gdap3xnikh6sia-string-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/gc3dzapn6kc264w7qpz6902a3c4fcwcw-string-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/rdfb4fgwbcnv6f4gn92f7qvf9w7di132-string-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jr3rprkfzy4zz9rh30g5pkncgpdvxh52-firewall-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3irl4xmn9l3ldkv4bqz2f79bvj2a6pmb-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3rs56j7083hacz98706j6mhm2qi0q8yp-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/r72wf1v5mcl2qw29ip031gy0zqhf02ln-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/353wlbspmn3y1k0265ldh7ijzvwymsv2-test-script.drv'
building '/nix/store/lmzyymwzxap2qwlwsw328k9kiqd2wcva-system-path.drv'
system-path> structuredAttrs is enabled
building '/nix/store/rdfb4fgwbcnv6f4gn92f7qvf9w7di132-string-hosts.drv'
system-path> created 1718 symlinks in user environment
building '/nix/store/0g806imy3syzv41jamsrvgx34x0h26dn-etc-hostname.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/3rs56j7083hacz98706j6mhm2qi0q8yp-dm.toml.drv'
dm.toml> structuredAttrs is enabled
building '/nix/store/8gbs8ccx9mz60xc7d8gdap3xnikh6sia-string-hosts.drv'
building '/nix/store/x76a7g5dm24l1aznv4psfw6i8ih04bpd-etc-hostname.drv'
building '/nix/store/gc3dzapn6kc264w7qpz6902a3c4fcwcw-string-hosts.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/gmkwwyyd54kzhwzvl6wsinpkbby3zj7i-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nidril14p2gv058jli8bi4cx3dzzn316-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vqk1n69bvywnl5qvqvmnp16wc4psw8b7-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/17n07jv6jvam4as7mcmvdijgd2c0yc96-users-groups.json.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/1za9j5r9yq44yzrhwbw84rbslfwp7y9c-vars-check-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3irl4xmn9l3ldkv4bqz2f79bvj2a6pmb-dm.toml.drv'
dm.toml> structuredAttrs is enabled
building '/nix/store/q42dfd84fnlxqv6jqjwwvz3888xb2k83-extra-hosts.drv'
building '/nix/store/qfr6cvpcrrgn4mxvz6w480zs48flvc6g-dbus-1.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jr3rprkfzy4zz9rh30g5pkncgpdvxh52-firewall-start.drv'
building '/nix/store/4kznmlsifwhp57kdigvw91klc85rwi6j-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/9n5zyyq1prf8qxknf9i7g8h7qllssd44-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/ws3h0b3alldwvfv9i39wrbwl75s1r52v-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/dmhkizs93876lr5xhxvfg8gxglg4cbyx-etc-hostname.drv'
building '/nix/store/r72wf1v5mcl2qw29ip031gy0zqhf02ln-dm.toml.drv'
dm.toml> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/iaaw79aqb7y7yj1aa1mla11mg2hwaj2g-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/7wi0981axipciiihfanzvcyx978dyz0j-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3flb4ly4vvzz23a4nhmqw47m2w728zdk-firewall-reload.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/g5gvlgd3msh0xlc0lj5gxf75n85dw6js-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/17n07jv6jvam4as7mcmvdijgd2c0yc96-users-groups.json.drv'
building '/nix/store/ws3h0b3alldwvfv9i39wrbwl75s1r52v-hosts.drv'
building '/nix/store/vqk1n69bvywnl5qvqvmnp16wc4psw8b7-decrypt-age-secrets.drv'
building '/nix/store/4kznmlsifwhp57kdigvw91klc85rwi6j-hosts.drv'
building '/nix/store/7gjdj504ic5scvcq63lnvwf3cqnfbzrz-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/iaaw79aqb7y7yj1aa1mla11mg2hwaj2g-X-Restart-Triggers-data-mesher.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/9n5zyyq1prf8qxknf9i7g8h7qllssd44-hosts.drv'
building '/nix/store/qfr6cvpcrrgn4mxvz6w480zs48flvc6g-dbus-1.drv'
building '/nix/store/1za9j5r9yq44yzrhwbw84rbslfwp7y9c-vars-check-data-mesher.drv'
building '/nix/store/7wi0981axipciiihfanzvcyx978dyz0j-X-Restart-Triggers-data-mesher.drv'
vars-check-data-mesher> Running vars check using Nix-based executor...
vars-check-data-mesher> ✓ All vars checks completed successfully
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/dbrhbxc2dbch59x4pvfpjvnjj3dwmzjw-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/fjzc8bc4g857h9y43yr5a6pvrcgcys5n-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/gmkwwyyd54kzhwzvl6wsinpkbby3zj7i-decrypt-age-secrets.drv'
building '/nix/store/fq6jyim33djp4qj9saisl4288hxsjpxx-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3flb4ly4vvzz23a4nhmqw47m2w728zdk-firewall-reload.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/dbrhbxc2dbch59x4pvfpjvnjj3dwmzjw-unit-data-mesher.service.drv'
unit-data-mesher.service> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/fjzc8bc4g857h9y43yr5a6pvrcgcys5n-unit-data-mesher.service.drv'
unit-data-mesher.service> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/af72bja4yb8kncarwmair860d6khypdn-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nidril14p2gv058jli8bi4cx3dzzn316-decrypt-age-secrets.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/jasqcrs9k4j2xckjgmfap73jx7cpakcr-unit-firewall.service.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/7gjdj504ic5scvcq63lnvwf3cqnfbzrz-dry-activate.drv'
building '/nix/store/af72bja4yb8kncarwmair860d6khypdn-X-Restart-Triggers-dbus-broker.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/fq6jyim33djp4qj9saisl4288hxsjpxx-dry-activate.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/g8q3xdhq3r2zz3m345m114xb7wbv23r7-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/kxga0cybvrshqwk8yk4khc8aqjmx9asf-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jasqcrs9k4j2xckjgmfap73jx7cpakcr-unit-firewall.service.drv'
unit-firewall.service> structuredAttrs is enabled
building '/nix/store/g5gvlgd3msh0xlc0lj5gxf75n85dw6js-X-Restart-Triggers-data-mesher.drv'
building '/nix/store/x7aazpndccqw92iv0aib8vlzjqmz9nhc-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/sj045h6ra5izps67lwccnhdb3ldl420m-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/g8q3xdhq3r2zz3m345m114xb7wbv23r7-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/3grdmn57di7np951s8scisqdv033xikc-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/xl06sz5bk0hrlx0j71i53dlgd4yz6n5k-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/x7aazpndccqw92iv0aib8vlzjqmz9nhc-unit-data-mesher.service.drv'
unit-data-mesher.service> structuredAttrs is enabled
building '/nix/store/xl06sz5bk0hrlx0j71i53dlgd4yz6n5k-system-units.drv'
building '/nix/store/kxga0cybvrshqwk8yk4khc8aqjmx9asf-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/dy8f61x9aj9rwpq0ij8rmnaw5q9q3zhn-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/2a4zkq3as2wc19qa0422mkq5pnpgm1zd-user-units.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/dy8f61x9aj9rwpq0ij8rmnaw5q9q3zhn-system-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/3grdmn57di7np951s8scisqdv033xikc-system-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/sj045h6ra5izps67lwccnhdb3ldl420m-dry-activate.drv'
building '/nix/store/2a4zkq3as2wc19qa0422mkq5pnpgm1zd-user-units.drv'
building '/nix/store/ddnw0l2cnlg2gi306fxklhnrkmaqxabd-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/hah4ls58vfvv33wa9wh5rdbkv5d0kdx6-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/lh6awj1bvm5rhygplnma3yi9aywdpgyq-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/ddnw0l2cnlg2gi306fxklhnrkmaqxabd-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/vpbwc4nlg375s3vrnfph02b0xqn6fmd4-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/hah4ls58vfvv33wa9wh5rdbkv5d0kdx6-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/lh6awj1bvm5rhygplnma3yi9aywdpgyq-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/vpbwc4nlg375s3vrnfph02b0xqn6fmd4-activate.drv'
building '/nix/store/x21v3hmqb7waxdp5hf4qd82i7v3h1987-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/rjc36af27m2xc2n29662rdqvfrhzkaxz-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0cid6a069zdi903rl60ykyryp0rvs7sv-nixos-system-beta-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0cid6a069zdi903rl60ykyryp0rvs7sv-nixos-system-beta-test.drv'
nixos-system-beta-test> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/rjc36af27m2xc2n29662rdqvfrhzkaxz-activate.drv'
building '/nix/store/x21v3hmqb7waxdp5hf4qd82i7v3h1987-activate.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/cmcalrphr5ndp4ghja7vn97rrxfxaffh-nixos-system-gamma-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/cmcalrphr5ndp4ghja7vn97rrxfxaffh-nixos-system-gamma-test.drv'
nixos-system-gamma-test> structuredAttrs is enabled
building '/nix/store/49hcic6ajc0ccc89gjcg8id317l74ka0-nixos-system-alpha-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/fgpwsvq97nzab77bzgsc23ndw75hq69y-run-gamma-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/092r3bcddzh8ynqb8mr21s447kbdkb2i-run-beta-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/092r3bcddzh8ynqb8mr21s447kbdkb2i-run-beta-nspawn.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/fgpwsvq97nzab77bzgsc23ndw75hq69y-run-gamma-nspawn.drv'
building '/nix/store/49hcic6ajc0ccc89gjcg8id317l74ka0-nixos-system-alpha-test.drv'
nixos-system-alpha-test> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/im7m23qqr5hrsr7m7w7k1l0c07f4p9ki-run-alpha-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/im7m23qqr5hrsr7m7w7k1l0c07f4p9ki-run-alpha-nspawn.drv'
building '/nix/store/lld001di2x7nrfrjnjcbz2bp1g4bncl7-driverConfiguration.json.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/lld001di2x7nrfrjnjcbz2bp1g4bncl7-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/fvi6nbw9s3xda2d7jvwjdyvv72dqv9ac-nixos-test-driver-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/fvi6nbw9s3xda2d7jvwjdyvv72dqv9ac-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!
building '/nix/store/vxpq33yvk0rdn91qzmmvyvnxndpa5157-container-test-run-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vxpq33yvk0rdn91qzmmvyvnxndpa5157-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 52)
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> beta: Waiting for journal at /build/vm-state-beta/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> (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(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> 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> 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 gamma on /build/vm-state-gamma.
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> 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> ░ Spawning container beta on /build/vm-state-beta.
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> beta # [5740893.707998] beta systemd-journald[88]: Journal started
container-test-run-data-mesher> beta # [5740893.708059] beta systemd-journald[88]: Runtime Journal (/run/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> beta # [5740893.711305] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> beta # [5740893.719193] beta systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [5740893.720039] beta systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [5740893.720776] beta systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [5740893.728176] beta systemd-journald[88]: Time spent on flushing to /var/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa is 2.284ms for 6 entries.
container-test-run-data-mesher> beta # [5740893.728176] beta systemd-journald[88]: System Journal (/var/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [5740893.735052] beta systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> beta # [5740893.735283] beta systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [5740893.735368] beta systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> beta # [5740893.736088] beta systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> gamma # [5740893.727083] gamma systemd-journald[87]: Journal started
container-test-run-data-mesher> beta # [5740893.736130] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [5740893.727132] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> beta # [5740893.736939] beta systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [5740893.732287] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> beta # [5740893.736973] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [5740893.742964] gamma systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [5740893.850884] beta systemd[1]: Finished Firewall.
container-test-run-data-mesher> gamma # [5740893.743691] gamma systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [5740893.851029] beta systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [5740893.744348] gamma systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [5740893.851254] beta systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [5740893.751603] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74 is 1.948ms for 6 entries.
container-test-run-data-mesher> beta # [5740893.852362] beta systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [5740893.751603] gamma systemd-journald[87]: System Journal (/var/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [5740893.892506] beta systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> beta # [5740893.893796] beta systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> beta # [5740893.915894] beta systemd-tmpfiles[199]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [5740893.916075] beta systemd-tmpfiles[199]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [5740893.916191] beta systemd-tmpfiles[199]: fchmod() of /var/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa failed: Operation not permitted
container-test-run-data-mesher> beta # [5740893.916367] beta systemd-tmpfiles[199]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [5740893.917760] beta systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> beta # [5740893.918714] beta systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> beta # [5740893.919361] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> beta # [5740893.932193] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> beta # [5740893.937898] beta systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> beta # [5740893.938963] beta systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [5740893.757208] gamma systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [5740893.757447] gamma systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [5740893.757531] gamma systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [5740893.758256] gamma systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> gamma # [5740893.758294] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [5740893.759059] gamma systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [5740893.759089] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [5740893.892488] gamma systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> gamma # [5740893.892766] gamma systemd[1]: Finished Firewall.
container-test-run-data-mesher> gamma # [5740893.893345] gamma systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [5740893.893621] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [5740893.894641] gamma systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [5740893.895373] gamma systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> gamma # [5740893.915532] gamma systemd-tmpfiles[197]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [5740893.915728] gamma systemd-tmpfiles[197]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [5740893.915866] gamma systemd-tmpfiles[197]: fchmod() of /var/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74 failed: Operation not permitted
container-test-run-data-mesher> gamma # [5740893.916180] gamma systemd-tmpfiles[197]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [5740893.917711] gamma systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [5740893.918791] gamma systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [5740893.919444] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [5740893.931872] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [5740893.711990] alpha systemd-journald[87]: Journal started
container-test-run-data-mesher> alpha # [5740893.712141] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> alpha # [5740893.720123] alpha systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> alpha # [5740893.720938] alpha systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> alpha # [5740893.721541] alpha systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> alpha # [5740893.728162] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4 is 2.086ms for 5 entries.
container-test-run-data-mesher> alpha # [5740893.728162] alpha systemd-journald[87]: System Journal (/var/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> alpha # [5740893.735516] alpha systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> alpha # [5740893.735742] alpha systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> alpha # [5740893.735824] alpha systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> alpha # [5740893.736530] alpha systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [5740893.736573] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [5740893.737314] alpha systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> alpha # [5740893.737348] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [5740893.850051] alpha systemd[1]: Finished Firewall.
container-test-run-data-mesher> alpha # [5740893.851062] alpha systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> alpha # [5740893.851338] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [5740893.852361] alpha systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [5740893.892545] alpha systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> alpha # [5740893.894177] alpha systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [5740893.911182] alpha systemd-tmpfiles[198]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [5740893.911383] alpha systemd-tmpfiles[198]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [5740893.911522] alpha systemd-tmpfiles[198]: fchmod() of /var/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4 failed: Operation not permitted
container-test-run-data-mesher> alpha # [5740893.911752] alpha systemd-tmpfiles[198]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [5740893.913712] alpha systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [5740893.914971] alpha systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> alpha # [5740893.915770] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [5740893.930735] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [5740893.936775] alpha systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [5740893.938500] alpha systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [5740893.942052] gamma systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> beta # [5740893.950595] beta systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [5740893.943096] gamma systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [5740893.953798] gamma systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> alpha # [5740893.950569] alpha systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> beta # [5740894.265615] beta systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [5740894.265715] beta systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [5740894.272231] beta systemd-networkd[195]: /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 # [5740894.272391] beta systemd-networkd[195]: /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 # [5740894.272559] beta systemd-networkd[195]: lo: Link UP
container-test-run-data-mesher> beta # [5740894.272562] beta systemd-networkd[195]: lo: Gained carrier
container-test-run-data-mesher> beta # [5740894.272778] beta systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [5740894.273295] beta systemd-networkd[195]: eth1: Link UP
container-test-run-data-mesher> beta # [5740894.273363] beta systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [5740894.273736] beta systemd-networkd[195]: eth1: Gained carrier
container-test-run-data-mesher> beta # [5740894.274381] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [5740894.346657] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [5740894.396787] beta systemd-resolved[111]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [5740894.396799] beta systemd-resolved[111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> beta # [5740894.396803] beta systemd-resolved[111]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> beta # [5740894.396837] beta systemd-resolved[111]: 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 # [5740894.419028] beta systemd-resolved[111]: Using system hostname 'beta'.
container-test-run-data-mesher> beta # [5740894.420465] beta systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> beta # [5740894.420554] beta systemd[1]: Reached target Network.
container-test-run-data-mesher> beta # [5740894.420617] beta systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> beta # [5740894.420663] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [5740894.420689] beta systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> beta # [5740894.420705] beta systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [5740894.420818] beta systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> beta # [5740894.420928] beta systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> beta # [5740894.421027] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> beta # [5740894.421046] beta systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [5740894.421077] beta systemd[1]: Reached target Basic System.
container-test-run-data-mesher> beta # [5740894.476411] beta systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> beta # [5740894.477452] beta systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> beta # [5740894.478376] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> beta # [5740894.479802] beta systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> beta # [5740894.495052] beta systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> gamma # [5740894.273242] gamma systemd-networkd[196]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [5740894.273862] gamma systemd-networkd[196]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [5740894.280462] gamma systemd-networkd[196]: /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 # [5740894.266487] alpha systemd-networkd[194]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [5740894.266573] alpha systemd-networkd[194]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [5740894.272918] alpha systemd-networkd[194]: /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 # [5740894.273082] alpha systemd-networkd[194]: /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 # [5740894.273269] alpha systemd-networkd[194]: lo: Link UP
container-test-run-data-mesher> alpha # [5740894.273272] alpha systemd-networkd[194]: lo: Gained carrier
container-test-run-data-mesher> alpha # [5740894.273642] alpha systemd-networkd[194]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [5740894.274007] alpha systemd[1]: Started Network Management.
container-test-run-data-mesher> alpha # [5740894.274213] alpha systemd-networkd[194]: eth1: Link UP
container-test-run-data-mesher> alpha # [5740894.274396] alpha systemd-networkd[194]: eth1: Gained carrier
container-test-run-data-mesher> alpha # [5740894.274915] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> gamma # [5740894.280623] gamma systemd-networkd[196]: /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 # [5740894.349993] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> gamma # [5740894.280777] gamma systemd-networkd[196]: lo: Link UP
container-test-run-data-mesher> alpha # [5740894.389343] alpha systemd-resolved[112]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [5740894.280781] gamma systemd-networkd[196]: lo: Gained carrier
container-test-run-data-mesher> alpha # [5740894.389361] alpha systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [5740894.280982] gamma systemd-networkd[196]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [5740894.389365] alpha systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [5740894.281421] gamma systemd-networkd[196]: eth1: Link UP
container-test-run-data-mesher> alpha # [5740894.389400] alpha systemd-resolved[112]: 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 # [5740894.281551] gamma systemd[1]: Started Network Management.
container-test-run-data-mesher> alpha # [5740894.412244] alpha systemd-resolved[112]: Using system hostname 'alpha'.
container-test-run-data-mesher> gamma # [5740894.281646] gamma systemd-networkd[196]: eth1: Gained carrier
container-test-run-data-mesher> alpha # [5740894.413691] alpha systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [5740894.282470] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> alpha # [5740894.413775] alpha systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [5740894.377494] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> alpha # [5740894.413838] alpha systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [5740894.403140] gamma systemd-resolved[112]: Positive Trust Anchors:
container-test-run-data-mesher> alpha # [5740894.413885] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [5740894.403154] gamma systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> alpha # [5740894.413916] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> gamma # [5740894.403157] gamma systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> alpha # [5740894.413962] alpha systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> gamma # [5740894.403192] gamma systemd-resolved[112]: 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 # [5740894.414082] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> gamma # [5740894.425644] gamma systemd-resolved[112]: Using system hostname 'gamma'.
container-test-run-data-mesher> alpha # [5740894.414306] alpha systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> gamma # [5740894.427035] gamma systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> alpha # [5740894.414416] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [5740894.427118] gamma systemd[1]: Reached target Network.
container-test-run-data-mesher> alpha # [5740894.414438] alpha systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> gamma # [5740894.427180] gamma systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> alpha # [5740894.414478] alpha systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [5740894.427229] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [5740894.415671] alpha systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [5740894.427254] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [5740894.416415] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [5740894.427273] gamma systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [5740894.417312] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> gamma # [5740894.427376] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> alpha # [5740894.418493] alpha systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> gamma # [5740894.427507] gamma systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [5740894.492160] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> gamma # [5740894.427612] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [5740894.427632] gamma systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> gamma # [5740894.427674] gamma systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [5740894.476476] gamma systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [5740894.477421] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [5740894.478325] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> gamma # [5740894.479850] gamma systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> gamma # [5740894.497976] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> gamma # [5740894.623600] gamma nsncd[211]: Aug 14 10:05:20.676 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> gamma # [5740894.623679] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> gamma # [5740894.623740] gamma systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> gamma # [5740894.623798] gamma systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> gamma # [5740894.644687] gamma systemd[1]: Starting User Login Management...
container-test-run-data-mesher> gamma # [5740894.645549] gamma systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [5740894.655353] gamma systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> gamma # [5740894.656534] gamma systemd[1]: Started Console Getty.
container-test-run-data-mesher> gamma # [5740894.656579] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> gamma # [5740894.656597] gamma systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [5740894.709434] gamma dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> gamma # [5740894.728832] gamma dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> gamma # [5740894.728832] gamma dbus-broker-launch[212]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> gamma # [5740894.729319] gamma systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> gamma # [5740894.737725] gamma dbus-broker-launch[212]: Ready
container-test-run-data-mesher> alpha # [5740894.604594] alpha nsncd[211]: Aug 14 10:05:20.657 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [5740894.604752] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [5740894.604830] alpha systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [5740894.604891] alpha systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> alpha # [5740894.644448] alpha systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [5740894.645427] alpha systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> alpha # [5740894.654884] alpha systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> alpha # [5740894.656541] alpha systemd[1]: Started Console Getty.
container-test-run-data-mesher> alpha # [5740894.656581] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> alpha # [5740894.656600] alpha systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> alpha # [5740894.719419] alpha dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> alpha # [5740894.720595] alpha dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> alpha # [5740894.720633] alpha dbus-broker-launch[212]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> alpha # [5740894.721127] alpha systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [5740894.728871] alpha dbus-broker-launch[212]: Ready
container-test-run-data-mesher> beta # [5740894.592372] beta nsncd[212]: Aug 14 10:05:20.645 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> beta # [5740894.592485] beta systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> beta # [5740894.592553] beta systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> beta # [5740894.592610] beta systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> beta # [5740894.593883] beta systemd[1]: Starting User Login Management...
container-test-run-data-mesher> beta # [5740894.594968] beta systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> beta # [5740894.651441] beta systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> beta # [5740894.652655] beta systemd[1]: Started Console Getty.
container-test-run-data-mesher> beta # [5740894.652711] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> beta # [5740894.652728] beta systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> beta # [5740894.706030] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [5740894.707041] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> beta # [5740894.707041] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [5740894.707458] beta systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> beta # [5740894.718309] beta dbus-broker-launch[213]: Ready
container-test-run-data-mesher> beta # [5740894.997987] beta data-mesher[210]: time=2026-08-14T10:05:21.051Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> beta # [5740894.999049] beta data-mesher[210]: time=2026-08-14T10:05:21.052Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4: [/dns/alpha.clan/tcp/7946]} {12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> beta # [5740894.999049] beta data-mesher[210]: time=2026-08-14T10:05:21.052Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> beta # [5740895.062149] beta data-mesher[210]: time=2026-08-14T10:05:21.115Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [5740895.062244] beta data-mesher[210]: time=2026-08-14T10:05:21.115Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="libp2p host created" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn 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 # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="starting server"
container-test-run-data-mesher> beta # [5740895.066706] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> beta # [5740895.067396] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> beta # [5740895.067719] beta data-mesher[210]: time=2026-08-14T10:05:21.120Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> beta # [5740895.073502] beta data-mesher[210]: time=2026-08-14T10:05:21.126Z level=INFO msg="peer connected" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [5740895.073891] beta data-mesher[210]: time=2026-08-14T10:05:21.127Z level=INFO msg="peer connected" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [5740895.081544] beta data-mesher[210]: time=2026-08-14T10:05:21.134Z level=INFO msg="peer connected" peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> beta # [5740895.144347] beta systemd-logind[229]: New seat seat0.
container-test-run-data-mesher> beta # [5740895.144540] beta systemd[1]: Started User Login Management.
container-test-run-data-mesher> beta # [5740895.181883] beta systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> beta # [5740895.196344] beta systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> beta # [5740895.196421] beta systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> gamma # [5740894.980872] gamma data-mesher[209]: time=2026-08-14T10:05:21.033Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> gamma # [5740894.981980] gamma data-mesher[209]: time=2026-08-14T10:05:21.035Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4: [/dns/alpha.clan/tcp/7946]} {12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> gamma # [5740894.981980] gamma data-mesher[209]: time=2026-08-14T10:05:21.035Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> gamma # [5740895.062064] gamma data-mesher[209]: time=2026-08-14T10:05:21.115Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> gamma # [5740895.062397] gamma data-mesher[209]: time=2026-08-14T10:05:21.115Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> gamma # [5740895.067368] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="libp2p host created" peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR 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 # [5740895.067368] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> gamma # [5740895.067493] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> gamma # [5740895.067493] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> gamma # [5740895.067493] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="starting server"
container-test-run-data-mesher> gamma # [5740895.067552] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> gamma # [5740895.067626] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> gamma # [5740895.067626] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> gamma # [5740895.073902] gamma data-mesher[209]: time=2026-08-14T10:05:21.126Z level=INFO msg="peer connected" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> gamma # [5740895.080529] gamma data-mesher[209]: time=2026-08-14T10:05:21.133Z level=INFO msg="peer connected" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> gamma # [5740895.132493] gamma systemd-logind[228]: New seat seat0.
container-test-run-data-mesher> gamma # [5740895.132673] gamma systemd[1]: Started User Login Management.
container-test-run-data-mesher> gamma # [5740895.180942] gamma systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [5740895.193632] gamma systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> gamma # [5740895.193788] gamma systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> alpha # [5740894.981090] alpha data-mesher[209]: time=2026-08-14T10:05:21.034Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> alpha # [5740894.982171] alpha data-mesher[209]: time=2026-08-14T10:05:21.035Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4: [/dns/alpha.clan/tcp/7946]} {12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> alpha # [5740894.982171] alpha data-mesher[209]: time=2026-08-14T10:05:21.035Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> alpha # [5740895.061851] alpha data-mesher[209]: time=2026-08-14T10:05:21.114Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> alpha # [5740895.062305] alpha data-mesher[209]: time=2026-08-14T10:05:21.115Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> alpha # [5740895.066324] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="libp2p host created" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 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 # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> alpha # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> alpha # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> alpha # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [5740895.066553] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [5740895.066586] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [5740895.066586] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [5740895.072292] alpha data-mesher[209]: time=2026-08-14T10:05:21.125Z level=INFO msg="peer connected" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> alpha # [5740895.073958] alpha data-mesher[209]: time=2026-08-14T10:05:21.127Z level=INFO msg="peer connected" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn remote_addr=/ip4/192.168.1.2/tcp/58246
container-test-run-data-mesher> alpha # [5740895.075730] alpha data-mesher[209]: time=2026-08-14T10:05:21.128Z level=INFO msg="peer connected" peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> alpha # [5740895.147582] alpha systemd-logind[228]: New seat seat0.
container-test-run-data-mesher> alpha # [5740895.147763] alpha systemd[1]: Started User Login Management.
container-test-run-data-mesher> alpha # [5740895.181020] alpha systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> alpha # [5740895.196333] alpha systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> alpha # [5740895.196402] alpha systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> alpha # [5740895.808515] alpha systemd-networkd[194]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [5740895.872219] beta systemd-networkd[195]: eth1: Gained IPv6LL
container-test-run-data-mesher> gamma # [5740895.972198] gamma systemd-networkd[196]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha # [5740896.153768] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> alpha # [5740896.154938] alpha systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> beta # [5740896.197245] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> beta # [5740896.198434] beta systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [5740896.223649] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [5740896.256258] gamma systemd[1]: Finished Save Transient machine-id to Disk.
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 12.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/a0lc46lb0aq973vlivllzdxhfsxfbjdj-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/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.12 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/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-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/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-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 # [5740905.067503] beta data-mesher[210]: time=2026-08-14T10:05:31.120Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> beta # [5740905.067503] beta data-mesher[210]: time=2026-08-14T10:05:31.120Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> beta # [5740905.068241] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740905.068241] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> beta # [5740905.068241] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="server started"
container-test-run-data-mesher> beta # [5740905.068380] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> beta # [5740905.068520] beta systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> beta # [5740905.069073] beta systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [5740905.069468] beta systemd[1]: Startup finished in 11.701s.
container-test-run-data-mesher> gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.122Z level=INFO msg="server started"
container-test-run-data-mesher> gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.122Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> gamma # [5740905.069067] gamma systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> gamma # [5740905.069371] gamma systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> gamma # [5740905.069555] gamma systemd[1]: Startup finished in 11.695s.
container-test-run-data-mesher> alpha # [5740905.067594] alpha data-mesher[209]: time=2026-08-14T10:05:31.120Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> alpha # [5740905.067594] alpha data-mesher[209]: time=2026-08-14T10:05:31.120Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> alpha # [5740905.068653] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740905.068653] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740905.068653] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [5740905.068827] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [5740905.068810] alpha systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [5740905.069123] alpha systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> alpha # [5740905.069327] alpha systemd[1]: Startup finished in 11.687s.
container-test-run-data-mesher> alpha # [5740905.274702] alpha data-mesher[209]: time=2026-08-14T10:05:31.327Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta # [5740910.069274] beta data-mesher[210]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [5740910.069683] beta data-mesher[210]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740910.069938] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740910.069990] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740910.070191] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740910.070191] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740910.070191] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [5740910.071545] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740910.071545] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740910.071727] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=DEBUG msg="new file detected" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 name=test_file name=test_file
container-test-run-data-mesher> beta # [5740910.071727] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> beta # [5740910.071824] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740910.090974] beta data-mesher[210]: time=2026-08-14T10:05:36.144Z level=INFO msg="download complete" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 written=true elapsed=19.223146ms
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 5.05 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 # [5740910.069328] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [5740910.069849] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> gamma # [5740910.069849] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740910.069849] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740910.070180] gamma data-mesher[209]: time=2026-08-14T10:05:36.123Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740910.070180] gamma data-mesher[209]: time=2026-08-14T10:05:36.123Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> gamma # [5740910.070180] gamma data-mesher[209]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [5740910.070564] alpha data-mesher[209]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [5740910.070904] alpha data-mesher[209]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740910.071912] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740910.071912] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740910.071975] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [5740910.072439] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=INFO msg="received file request" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_file
container-test-run-data-mesher> alpha # [5740910.074261] alpha data-mesher[209]: time=2026-08-14T10:05:36.127Z level=INFO msg="file transfer complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_file
container-test-run-data-mesher> beta # [5740915.070739] beta data-mesher[210]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [5740915.071186] beta data-mesher[210]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740915.071443] beta data-mesher[210]: time=2026-08-14T10:05:41.124Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740915.071489] beta data-mesher[210]: time=2026-08-14T10:05:41.124Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740915.072171] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740915.072171] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740915.072247] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [5740915.072470] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="received file request" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_file
container-test-run-data-mesher> beta # [5740915.072835] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740915.072868] beta data-mesher[210]: time=2026-08-14T10:05:41.126Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740915.073743] beta data-mesher[210]: time=2026-08-14T10:05:41.126Z level=INFO msg="file transfer complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=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 5.04 seconds)
container-test-run-data-mesher> beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> gamma # [5740915.070746] gamma data-mesher[209]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [5740915.071189] gamma data-mesher[209]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> gamma # [5740915.071511] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740915.071551] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740915.071586] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=DEBUG msg="new file detected" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file name=test_file
container-test-run-data-mesher> gamma # [5740915.071667] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> gamma # [5740915.071731] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="new file detected" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file name=test_file
container-test-run-data-mesher> gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [5740915.101773] gamma data-mesher[209]: time=2026-08-14T10:05:41.154Z level=INFO msg="download complete" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn written=true elapsed=30.042735ms
container-test-run-data-mesher> beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-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> alpha # [5740915.072477] alpha data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [5740915.072477] alpha data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740915.073494] alpha data-mesher[209]: time=2026-08-14T10:05:41.126Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740915.073533] alpha data-mesher[209]: time=2026-08-14T10:05:41.126Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740915.073557] alpha data-mesher[209]: time=2026-08-14T10:05:41.126Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [5740915.408878] beta data-mesher[210]: time=2026-08-14T10:05:41.462Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta # [5740920.072545] beta data-mesher[210]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [5740920.073208] beta data-mesher[210]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740920.144970] beta data-mesher[210]: time=2026-08-14T10:05:46.198Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740920.145040] beta data-mesher[210]: time=2026-08-14T10:05:46.198Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740920.145040] beta data-mesher[210]: time=2026-08-14T10:05:46.198Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [5740920.072463] gamma data-mesher[209]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [5740920.072463] gamma data-mesher[209]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740920.073199] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740920.073262] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740920.073405] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740920.073485] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740920.073485] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [5740920.074226] gamma data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740920.074226] gamma data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740920.144644] gamma data-mesher[209]: time=2026-08-14T10:05:46.197Z level=DEBUG msg="imported tombstone" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file written=true
container-test-run-data-mesher> alpha # [5740920.072998] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740920.072998] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740920.073714] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [5740920.073714] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> alpha # [5740920.074558] alpha data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740920.085319] alpha data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> alpha # [5740920.085319] alpha data-mesher[209]: time=2026-08-14T10:05:46.127Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [5740925.074042] gamma data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [5740925.074745] gamma data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740925.077226] gamma data-mesher[209]: time=2026-08-14T10:05:51.130Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740925.077307] gamma data-mesher[209]: time=2026-08-14T10:05:51.130Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740925.077307] gamma data-mesher[209]: time=2026-08-14T10:05:51.130Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [5740925.146598] gamma data-mesher[209]: time=2026-08-14T10:05:51.199Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740925.146598] gamma data-mesher[209]: time=2026-08-14T10:05:51.199Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740925.074702] alpha data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [5740925.074702] alpha data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740925.075471] alpha data-mesher[209]: time=2026-08-14T10:05:51.127Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740925.075471] alpha data-mesher[209]: time=2026-08-14T10:05:51.128Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740925.075670] alpha data-mesher[209]: time=2026-08-14T10:05:51.128Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740925.076919] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z level=DEBUG msg="imported tombstone" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR name=test_file written=true
container-test-run-data-mesher> alpha # [5740925.077042] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z level=DEBUG msg="imported tombstone" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file written=false
container-test-run-data-mesher> alpha # [5740925.077042] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740925.077153] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z 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 10.09 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> 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/7pbnci0jzjsc1c2ml5zg1pqxkwz1iffr-per-machine-alpha-data-mesher-node-identity_identity.pub
container-test-run-data-mesher> alpha: (finished: must succeed: cat /nix/store/7pbnci0jzjsc1c2ml5zg1pqxkwz1iffr-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: (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/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-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/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-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/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> beta # [5740925.075291] beta data-mesher[210]: time=2026-08-14T10:05:51.128Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740925.075291] beta data-mesher[210]: time=2026-08-14T10:05:51.128Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740925.146004] beta data-mesher[210]: time=2026-08-14T10:05:51.199Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [5740925.146103] beta data-mesher[210]: time=2026-08-14T10:05:51.199Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740925.146921] beta data-mesher[210]: time=2026-08-14T10:05:51.200Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740925.146987] beta data-mesher[210]: time=2026-08-14T10:05:51.200Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740925.147040] beta data-mesher[210]: time=2026-08-14T10:05:51.200Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [5740925.555262] alpha data-mesher[209]: time=2026-08-14T10:05:51.608Z level=INFO msg=http_request uri=/files/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc status=204
container-test-run-data-mesher> gamma # [5740930.077627] gamma data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [5740930.078311] gamma data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740930.078777] gamma data-mesher[209]: time=2026-08-14T10:05:56.131Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740930.079395] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="new file detected" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 name=test_file name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> gamma # [5740930.079395] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s
container-test-run-data-mesher> gamma # [5740930.079541] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="scheduling file download" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> gamma # [5740930.079541] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [5740930.079541] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="downloading file" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> gamma # [5740930.083769] gamma data-mesher[209]: time=2026-08-14T10:05:56.136Z level=INFO msg="download complete" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 written=true elapsed=4.261339ms
container-test-run-data-mesher> gamma # [5740930.152032] gamma data-mesher[209]: time=2026-08-14T10:05:56.201Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> gamma # [5740930.152032] gamma data-mesher[209]: time=2026-08-14T10:05:56.201Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740930.077509] alpha data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [5740930.077509] alpha data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740930.078320] alpha data-mesher[209]: time=2026-08-14T10:05:56.131Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740930.078320] alpha data-mesher[209]: time=2026-08-14T10:05:56.131Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> alpha # [5740930.079202] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn
container-test-run-data-mesher> alpha # [5740930.079276] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s
container-test-run-data-mesher> alpha # [5740930.079276] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [5740930.079627] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="received file request" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> alpha # [5740930.080121] alpha data-mesher[209]: time=2026-08-14T10:05:56.133Z level=INFO msg="received file request" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> alpha # [5740930.082095] alpha data-mesher[209]: time=2026-08-14T10:05:56.135Z level=INFO msg="file transfer complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> alpha # [5740930.082231] alpha data-mesher[209]: time=2026-08-14T10:05:56.135Z level=INFO msg="file transfer complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> beta # [5740930.078248] beta data-mesher[210]: time=2026-08-14T10:05:56.131Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740930.078248] beta data-mesher[210]: time=2026-08-14T10:05:56.131Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740930.078966] beta data-mesher[210]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="new file detected" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 name=test_file name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> beta # [5740930.079028] beta data-mesher[210]: time=2026-08-14T10:05:56.132Z level=INFO msg="scheduling file download" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> beta # [5740930.079136] beta data-mesher[210]: time=2026-08-14T10:05:56.132Z level=INFO msg="downloading file" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4
container-test-run-data-mesher> beta # [5740930.083817] beta data-mesher[210]: time=2026-08-14T10:05:56.136Z level=INFO msg="download complete" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 written=true elapsed=4.723625ms
container-test-run-data-mesher> beta # [5740930.147883] beta data-mesher[210]: time=2026-08-14T10:05:56.200Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [5740930.148072] beta data-mesher[210]: time=2026-08-14T10:05:56.201Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740930.149578] beta data-mesher[210]: time=2026-08-14T10:05:56.202Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR
container-test-run-data-mesher> beta # [5740930.150196] beta data-mesher[210]: time=2026-08-14T10:05:56.203Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s
container-test-run-data-mesher> beta # [5740930.150260] beta data-mesher[210]: time=2026-08-14T10:05:56.203Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc, in 5.05 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc, in 0.01 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/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub
container-test-run-data-mesher> Error: failed to update file: 403 Forbidden, signer iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc= is not authorized for this file test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc
container-test-run-data-mesher> alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub, in 0.03 seconds)
container-test-run-data-mesher> (finished: run the VM test script, in 37.73 seconds)
container-test-run-data-mesher> test script finished in 37.90s
container-test-run-data-mesher> cleanup
container-test-run-data-mesher> kill NspawnMachine (pid 52)
container-test-run-data-mesher> kill NspawnMachine (pid 54)
container-test-run-data-mesher> alpha # [5740930.660419] alpha data-mesher[209]: time=2026-08-14T10:05:56.713Z level=INFO msg=http_request uri=/files/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc status=403
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)

post-build step Upload to niks3: ok
time=2026-08-14T10:05:57.989Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-08-14T10:05:58.807Z level=INFO msg="Uploading 1 narinfos"
time=2026-08-14T10:05:59.882Z level=INFO msg="Upload complete. (1.993s)"