these 67 derivations will be built:
  /nix/store/326hxwzjsrnzmf3zxxfpzbvpy37sb5r4-dm.toml.drv
  /nix/store/dc5q09zk1gfpmw5jbn5wf7rj8s20wpsm-string-hosts.drv
  /nix/store/l5xv9r1jb477pq49pkjyrcfpvs3naqzq-extra-hosts.drv
  /nix/store/5w5xp5j3y9kmx3c4ryg614amc9jc19c6-hosts.drv
  /nix/store/clf53xpmzfs225vjj2y3n824348czmri-system-path.drv
  /nix/store/f9sv17vg179r7hq4x21nvgjymkqc69k8-etc-hostname.drv
  /nix/store/havvb94bmw9kyvybkavqbbwgjfpv71wa-dbus-1.drv
  /nix/store/az99nm2kxv7bj1v41dzgc2xl9221say8-X-Restart-Triggers-dbus-broker.drv
  /nix/store/ig295pyj8l3cvyvv5f4w2g3zv03g556s-unit-dbus-broker.service.drv
  /nix/store/j8m6wb5wz9pir9da2i3kj8n1cfc7kmg1-user-units.drv
  /nix/store/2h42afq208nsvj1wbqr9pbnx76h33rlj-unit-systemd-nspawn-.service.drv
  /nix/store/3sxvv98hxqk0rgzf535xvwx97mkfac8r-unit-suid-sgid-wrappers.service-disabled.drv
  /nix/store/jl71dd3zm8qkx75qxqsz6syvcnl35b08-X-Restart-Triggers-data-mesher.drv
  /nix/store/cviqm4h5af6my8dxd746hz7aj1psdnwp-unit-data-mesher.service.drv
  /nix/store/h7qnb5al6slb8jxpdp1yyr05xd4pqkwr-unit-systemd-pstore.service.drv
  /nix/store/ic0blwzkx7h791g02hvq14lah6jw82b9-unit-systemd-random-seed.service.drv
  /nix/store/jgc0dw7h96h50c11pb9vvn7cxm49q002-unit-linger-users.service.drv
  /nix/store/jpdf6hlg9y1mf2jwq5l8vyfdrn263i6j-unit-dbus-broker.service.drv
  /nix/store/kpjix8xa2arg5sq1qfdxddbgmqjbxv2x-unit-systemd-fsck-.service.drv
  /nix/store/lx2lmak5dcafy99w6xnwy4gm3hnwd9h0-unit-systemd-makefs-.service.drv
  /nix/store/mpdzgbmnck19z8lpk8756vinb6jk59f2-unit-serial-getty-hvc0.service-disabled.drv
  /nix/store/n8l0idik1mhc8dnz1s5ddx8ld59iv43q-unit-serial-getty-.service.drv
  /nix/store/njqibx1w3ha78whcw0bdfn4k1szqq0d7-unit-systemd-networkd-wait-online-.service.drv
  /nix/store/f7afi57gk668fdjzbdamyxdnqfa5027p-firewall-start.drv
  /nix/store/pxn48ls7q2y9k9v059sk8qcydhpdcdcy-firewall-reload.drv
  /nix/store/qpcj23hr7j5jfv9cg81l6rf3wqqzx0i8-unit-firewall.service.drv
  /nix/store/wbklqpy0wlllrd1ps4yz262psak1wxkd-unit-user-runtime-dir-.service.drv
  /nix/store/wi030ms96f2c6ffdnz4z5bwkz19s1zxw-system-units.drv
  /nix/store/ydlgz666szgf1n62mmhmij27fi8xdjf7-vars-check-data-mesher.drv
  /nix/store/0ikjzx6z18xawawlzpajfk8lsbzcd92v-etc.drv
  /nix/store/c859fgkpmhdhjvliadjw4wp07crbxqjh-decrypt-age-secrets.drv
  /nix/store/qxlyl4hhcxv8dnvylrh42ydrlff2ic9a-users-groups.json.drv
  /nix/store/3f5n3cbzaz54j282kdq3w3xca2fnwwpq-activate.drv
  /nix/store/jqvhh9griyfxibqych31c7hxxfzx8plm-dry-activate.drv
  /nix/store/zdqh9lxd4vn83gy99sim81v0add6xm1s-nixos-system-alpha-test.drv
  /nix/store/24ag1rdzjw2a6sqdqhkc44jiaiqz6lkx-run-alpha-nspawn.drv
  /nix/store/3h7l9lyi5mi3k61y6mkzbpnv09zwgkaj-dm.toml.drv
  /nix/store/4yjhb1f88q0r3kz25mjxcr396ljkki9s-string-hosts.drv
  /nix/store/4av6fz7d65yrs9j2bw1a3xmwkp9q0gpm-hosts.drv
  /nix/store/i2ir8y8iixzh870k0y1ikbb5qngs45n5-decrypt-age-secrets.drv
  /nix/store/8a2s24zjbid4hnqil25yj9d7f8dq0lv6-dry-activate.drv
  /nix/store/8435n441b84qzbp1v84cp3wngf7n8k5b-dm.toml.drv
  /nix/store/6bkpfq6di05bsdmg78gfm1ja76hr2ddx-string-hosts.drv
  /nix/store/kiz8iian5jpv734wfjgf5ilxdvbk5faa-hosts.drv
  /nix/store/nl7jrkfanmylp1b5hnshqsb24jchga7g-etc-hostname.drv
  /nix/store/jwvls80cqz4nh5raaq65ab53kbxg2jr7-unit-systemd-networkd.service.drv
  /nix/store/f2f1i54j6ivqns9fw9hpnyypb10p7s3y-X-Restart-Triggers-data-mesher.drv
  /nix/store/p2yjcvvjh403ii1argc9ifd7n56zad1h-unit-data-mesher.service.drv
  /nix/store/sdry7rkzi52772ljiaqy8cqq5racgbl7-system-units.drv
  /nix/store/gsqdc39s8sskqyh012mff5a1n1d1hl8x-etc.drv
  /nix/store/h2nb6dvhb9w33x2c9j4k1z3819xs3id1-activate.drv
  /nix/store/i0ig7z50ly7spsc7mnxaqchwq0ljmcab-nixos-system-gamma-test.drv
  /nix/store/9r6a8qc6h6jq2pld0w6xp6ybq3png070-run-gamma-nspawn.drv
  /nix/store/isjfzjlhjlrpn7sdzv6v5mk6j6wshcnh-decrypt-age-secrets.drv
  /nix/store/agnmz6hxj3l019ddc10h6zfx5hq7cdsd-dry-activate.drv
  /nix/store/dj5n8yz5040g04is2imfjncx9myyf0rv-X-Restart-Triggers-data-mesher.drv
  /nix/store/sfa80c92m5fsv86w3rwvri0nxlqiss2a-unit-data-mesher.service.drv
  /nix/store/dhj77i35pmw1gfcv8zrf30z5yx1y6hld-system-units.drv
  /nix/store/jf3hyzd1zh5x0xwfgmbf3hb1fadv0zs7-etc-hostname.drv
  /nix/store/jvlc30x8a902x7smrz7fc2mpgqh2n607-etc.drv
  /nix/store/m1ahjg91v8pn6n5sqf3bx4q5rxhpbvx8-activate.drv
  /nix/store/wqa3wl8p9q0qa76v0cysqvqvl5w8zjkj-nixos-system-beta-test.drv
  /nix/store/md3nf1n25l03qn8afnmz2lbi8613nw2v-run-beta-nspawn.drv
  /nix/store/xg54pci6z1vhwdzlq59bwbrp2ssf7jq4-test-script.drv
  /nix/store/5j76xbqjzsizrj1wgk6i3ccb7m22nhln-driverConfiguration.json.drv
  /nix/store/9y0vr6pa63aqz5b494higsqbv045z6gw-nixos-test-driver-data-mesher.drv
  /nix/store/zcqinwhy3sk9wlzzz5cb2hbpvvfn9hqj-container-test-run-data-mesher.drv
building '/nix/store/3h7l9lyi5mi3k61y6mkzbpnv09zwgkaj-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/c859fgkpmhdhjvliadjw4wp07crbxqjh-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3h7l9lyi5mi3k61y6mkzbpnv09zwgkaj-dm.toml.drv'
dm.toml> structuredAttrs is enabled
building '/nix/store/dj5n8yz5040g04is2imfjncx9myyf0rv-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/i2ir8y8iixzh870k0y1ikbb5qngs45n5-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/isjfzjlhjlrpn7sdzv6v5mk6j6wshcnh-decrypt-age-secrets.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/c859fgkpmhdhjvliadjw4wp07crbxqjh-decrypt-age-secrets.drv'
decrypt-age-secrets> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/dj5n8yz5040g04is2imfjncx9myyf0rv-X-Restart-Triggers-data-mesher.drv'
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
building '/nix/store/sfa80c92m5fsv86w3rwvri0nxlqiss2a-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/i2ir8y8iixzh870k0y1ikbb5qngs45n5-decrypt-age-secrets.drv'
decrypt-age-secrets> structuredAttrs is enabled
building '/nix/store/isjfzjlhjlrpn7sdzv6v5mk6j6wshcnh-decrypt-age-secrets.drv'
decrypt-age-secrets> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/sfa80c92m5fsv86w3rwvri0nxlqiss2a-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/4yjhb1f88q0r3kz25mjxcr396ljkki9s-string-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/6bkpfq6di05bsdmg78gfm1ja76hr2ddx-string-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/dc5q09zk1gfpmw5jbn5wf7rj8s20wpsm-string-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/4yjhb1f88q0r3kz25mjxcr396ljkki9s-string-hosts.drv'
string-hosts> structuredAttrs is enabled
building '/nix/store/6bkpfq6di05bsdmg78gfm1ja76hr2ddx-string-hosts.drv'
string-hosts> 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/dc5q09zk1gfpmw5jbn5wf7rj8s20wpsm-string-hosts.drv'
string-hosts> structuredAttrs is enabled
building '/nix/store/326hxwzjsrnzmf3zxxfpzbvpy37sb5r4-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/8435n441b84qzbp1v84cp3wngf7n8k5b-dm.toml.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/f9sv17vg179r7hq4x21nvgjymkqc69k8-etc-hostname.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/326hxwzjsrnzmf3zxxfpzbvpy37sb5r4-dm.toml.drv'
dm.toml> structuredAttrs is enabled
building '/nix/store/jl71dd3zm8qkx75qxqsz6syvcnl35b08-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jf3hyzd1zh5x0xwfgmbf3hb1fadv0zs7-etc-hostname.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/f9sv17vg179r7hq4x21nvgjymkqc69k8-etc-hostname.drv'
etc-hostname> structuredAttrs is enabled
building '/nix/store/8435n441b84qzbp1v84cp3wngf7n8k5b-dm.toml.drv'
dm.toml> structuredAttrs is enabled
building '/nix/store/f2f1i54j6ivqns9fw9hpnyypb10p7s3y-X-Restart-Triggers-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jl71dd3zm8qkx75qxqsz6syvcnl35b08-X-Restart-Triggers-data-mesher.drv'
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
building '/nix/store/cviqm4h5af6my8dxd746hz7aj1psdnwp-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jf3hyzd1zh5x0xwfgmbf3hb1fadv0zs7-etc-hostname.drv'
etc-hostname> structuredAttrs is enabled
building '/nix/store/f2f1i54j6ivqns9fw9hpnyypb10p7s3y-X-Restart-Triggers-data-mesher.drv'
X-Restart-Triggers-data-mesher> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/p2yjcvvjh403ii1argc9ifd7n56zad1h-unit-data-mesher.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nl7jrkfanmylp1b5hnshqsb24jchga7g-etc-hostname.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/l5xv9r1jb477pq49pkjyrcfpvs3naqzq-extra-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/cviqm4h5af6my8dxd746hz7aj1psdnwp-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/p2yjcvvjh403ii1argc9ifd7n56zad1h-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/f7afi57gk668fdjzbdamyxdnqfa5027p-firewall-start.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/clf53xpmzfs225vjj2y3n824348czmri-system-path.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nl7jrkfanmylp1b5hnshqsb24jchga7g-etc-hostname.drv'
etc-hostname> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/clf53xpmzfs225vjj2y3n824348czmri-system-path.drv'
system-path> structuredAttrs is enabled
system-path> created 1718 symlinks in user environment
building '/nix/store/l5xv9r1jb477pq49pkjyrcfpvs3naqzq-extra-hosts.drv'
extra-hosts> 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/4av6fz7d65yrs9j2bw1a3xmwkp9q0gpm-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/5w5xp5j3y9kmx3c4ryg614amc9jc19c6-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/kiz8iian5jpv734wfjgf5ilxdvbk5faa-hosts.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/havvb94bmw9kyvybkavqbbwgjfpv71wa-dbus-1.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/f7afi57gk668fdjzbdamyxdnqfa5027p-firewall-start.drv'
firewall-start> structuredAttrs is enabled
building '/nix/store/pxn48ls7q2y9k9v059sk8qcydhpdcdcy-firewall-reload.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/4av6fz7d65yrs9j2bw1a3xmwkp9q0gpm-hosts.drv'
building '/nix/store/5w5xp5j3y9kmx3c4ryg614amc9jc19c6-hosts.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/pxn48ls7q2y9k9v059sk8qcydhpdcdcy-firewall-reload.drv'
firewall-reload> 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/kiz8iian5jpv734wfjgf5ilxdvbk5faa-hosts.drv'
building '/nix/store/xg54pci6z1vhwdzlq59bwbrp2ssf7jq4-test-script.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/qpcj23hr7j5jfv9cg81l6rf3wqqzx0i8-unit-firewall.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/havvb94bmw9kyvybkavqbbwgjfpv71wa-dbus-1.drv'
building '/nix/store/az99nm2kxv7bj1v41dzgc2xl9221say8-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/qxlyl4hhcxv8dnvylrh42ydrlff2ic9a-users-groups.json.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
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/qpcj23hr7j5jfv9cg81l6rf3wqqzx0i8-unit-firewall.service.drv'
unit-firewall.service> structuredAttrs is enabled
building '/nix/store/xg54pci6z1vhwdzlq59bwbrp2ssf7jq4-test-script.drv'
test-script> structuredAttrs is enabled
building '/nix/store/az99nm2kxv7bj1v41dzgc2xl9221say8-X-Restart-Triggers-dbus-broker.drv'
X-Restart-Triggers-dbus-broker> structuredAttrs is enabled
building '/nix/store/ig295pyj8l3cvyvv5f4w2g3zv03g556s-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/qxlyl4hhcxv8dnvylrh42ydrlff2ic9a-users-groups.json.drv'
users-groups.json> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/8a2s24zjbid4hnqil25yj9d7f8dq0lv6-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/agnmz6hxj3l019ddc10h6zfx5hq7cdsd-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jqvhh9griyfxibqych31c7hxxfzx8plm-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jpdf6hlg9y1mf2jwq5l8vyfdrn263i6j-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/ydlgz666szgf1n62mmhmij27fi8xdjf7-vars-check-data-mesher.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/ig295pyj8l3cvyvv5f4w2g3zv03g556s-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/8a2s24zjbid4hnqil25yj9d7f8dq0lv6-dry-activate.drv'
dry-activate> structuredAttrs is enabled
building '/nix/store/j8m6wb5wz9pir9da2i3kj8n1cfc7kmg1-user-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/agnmz6hxj3l019ddc10h6zfx5hq7cdsd-dry-activate.drv'
dry-activate> 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/jqvhh9griyfxibqych31c7hxxfzx8plm-dry-activate.drv'
dry-activate> structuredAttrs is enabled
building '/nix/store/ydlgz666szgf1n62mmhmij27fi8xdjf7-vars-check-data-mesher.drv'
vars-check-data-mesher> Running vars check using Nix-based executor...
vars-check-data-mesher> ✓ All vars checks completed successfully
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/jpdf6hlg9y1mf2jwq5l8vyfdrn263i6j-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/j8m6wb5wz9pir9da2i3kj8n1cfc7kmg1-user-units.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/dhj77i35pmw1gfcv8zrf30z5yx1y6hld-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/sdry7rkzi52772ljiaqy8cqq5racgbl7-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/wi030ms96f2c6ffdnz4z5bwkz19s1zxw-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
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/sdry7rkzi52772ljiaqy8cqq5racgbl7-system-units.drv'
building '/nix/store/wi030ms96f2c6ffdnz4z5bwkz19s1zxw-system-units.drv'
building '/nix/store/dhj77i35pmw1gfcv8zrf30z5yx1y6hld-system-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/0ikjzx6z18xawawlzpajfk8lsbzcd92v-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/gsqdc39s8sskqyh012mff5a1n1d1hl8x-etc.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
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/gsqdc39s8sskqyh012mff5a1n1d1hl8x-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/0ikjzx6z18xawawlzpajfk8lsbzcd92v-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/3f5n3cbzaz54j282kdq3w3xca2fnwwpq-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/h2nb6dvhb9w33x2c9j4k1z3819xs3id1-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/jvlc30x8a902x7smrz7fc2mpgqh2n607-etc.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/h2nb6dvhb9w33x2c9j4k1z3819xs3id1-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/i0ig7z50ly7spsc7mnxaqchwq0ljmcab-nixos-system-gamma-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/3f5n3cbzaz54j282kdq3w3xca2fnwwpq-activate.drv'
activate> 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/zdqh9lxd4vn83gy99sim81v0add6xm1s-nixos-system-alpha-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/zdqh9lxd4vn83gy99sim81v0add6xm1s-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
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/jvlc30x8a902x7smrz7fc2mpgqh2n607-etc.drv'
building '/nix/store/24ag1rdzjw2a6sqdqhkc44jiaiqz6lkx-run-alpha-nspawn.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/m1ahjg91v8pn6n5sqf3bx4q5rxhpbvx8-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/i0ig7z50ly7spsc7mnxaqchwq0ljmcab-nixos-system-gamma-test.drv'
nixos-system-gamma-test> structuredAttrs is enabled
building '/nix/store/24ag1rdzjw2a6sqdqhkc44jiaiqz6lkx-run-alpha-nspawn.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/9r6a8qc6h6jq2pld0w6xp6ybq3png070-run-gamma-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/m1ahjg91v8pn6n5sqf3bx4q5rxhpbvx8-activate.drv'
activate> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/wqa3wl8p9q0qa76v0cysqvqvl5w8zjkj-nixos-system-beta-test.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/9r6a8qc6h6jq2pld0w6xp6ybq3png070-run-gamma-nspawn.drv'
building '/nix/store/wqa3wl8p9q0qa76v0cysqvqvl5w8zjkj-nixos-system-beta-test.drv'
nixos-system-beta-test> structuredAttrs is enabled
building '/nix/store/md3nf1n25l03qn8afnmz2lbi8613nw2v-run-beta-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/md3nf1n25l03qn8afnmz2lbi8613nw2v-run-beta-nspawn.drv'
building '/nix/store/5j76xbqjzsizrj1wgk6i3ccb7m22nhln-driverConfiguration.json.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/5j76xbqjzsizrj1wgk6i3ccb7m22nhln-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/9y0vr6pa63aqz5b494higsqbv045z6gw-nixos-test-driver-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/9y0vr6pa63aqz5b494higsqbv045z6gw-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!
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/zcqinwhy3sk9wlzzz5cb2hbpvvfn9hqj-container-test-run-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/zcqinwhy3sk9wlzzz5cb2hbpvvfn9hqj-container-test-run-data-mesher.drv'
container-test-run-data-mesher> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-data-mesher> start all VLans
container-test-run-data-mesher> (finished: start all VLans, in 0.00 seconds)
container-test-run-data-mesher> 
container-test-run-data-mesher> Test will time out and terminate in 3600.0 seconds
container-test-run-data-mesher> run the VM test script
container-test-run-data-mesher> additionally exposed symbols:
container-test-run-data-mesher>     alpha, beta, gamma,
container-test-run-data-mesher>     vlan1,
container-test-run-data-mesher>     start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
container-test-run-data-mesher> start all VMs
container-test-run-data-mesher> alpha: systemd-nspawn running (pid 53)
container-test-run-data-mesher> 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: systemd-nspawn running (pid 54)
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> 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> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> ░ Spawning container gamma on /build/vm-state-gamma.
container-test-run-data-mesher> ░ Spawning container beta on /build/vm-state-beta.
container-test-run-data-mesher> ░ Spawning container alpha on /build/vm-state-alpha.
container-test-run-data-mesher> alpha # No journal files were found.
container-test-run-data-mesher> alpha # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> beta # No journal files were found.
container-test-run-data-mesher> beta # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> gamma # No journal files were found.
container-test-run-data-mesher> gamma # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> alpha # [805635.508401] alpha systemd-journald[90]: Journal started
container-test-run-data-mesher> alpha # [805635.508463] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/5e565a6d26544dc6a584e06df8fcfd99) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> alpha # [805635.510789] alpha systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> alpha # [805635.522849] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> alpha # [805635.560480] alpha systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> alpha # [805635.561541] alpha systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> alpha # [805635.562410] alpha systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> alpha # [805635.571423] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/5e565a6d26544dc6a584e06df8fcfd99 is 1.910ms for 7 entries.
container-test-run-data-mesher> alpha # [805635.571423] alpha systemd-journald[90]: System Journal (/var/log/journal/5e565a6d26544dc6a584e06df8fcfd99) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> alpha # [805635.594503] alpha systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> alpha # [805635.595799] alpha systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> alpha # [805635.596923] alpha systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> alpha # [805635.597049] alpha systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> alpha # [805635.597915] alpha systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [805635.597962] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [805635.598778] alpha systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> alpha # [805635.599598] alpha systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [805635.599632] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [805635.618833] alpha systemd-tmpfiles[159]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [805635.619060] alpha systemd-tmpfiles[159]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [805635.619217] alpha systemd-tmpfiles[159]: fchmod() of /var/log/journal/5e565a6d26544dc6a584e06df8fcfd99 failed: Operation not permitted
container-test-run-data-mesher> alpha # [805635.619452] alpha systemd-tmpfiles[159]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [805635.620438] alpha systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [805635.622033] alpha systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> alpha # [805635.622689] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [805635.635385] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [805635.646958] alpha systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [805635.648283] alpha systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> alpha # [805635.659420] alpha systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> alpha # [805635.675467] alpha systemd[1]: Finished Firewall.
container-test-run-data-mesher> alpha # [805635.675645] alpha systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> alpha # [805635.675871] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [805635.676893] alpha systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [805635.714398] alpha systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> alpha # [805636.417848] alpha systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [805636.417935] alpha systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [805636.427231] alpha systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> alpha # [805636.427414] alpha systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> alpha # [805636.427573] alpha systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> alpha # [805636.427576] alpha systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> alpha # [805636.427744] alpha systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [805636.428179] alpha systemd[1]: Started Network Management.
container-test-run-data-mesher> alpha # [805636.448304] alpha systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> alpha # [805636.448338] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> alpha # [805636.448965] alpha systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> alpha # [805636.500118] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> alpha # [805636.503147] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [805635.493450] beta systemd-journald[89]: Journal started
container-test-run-data-mesher> beta # [805635.493507] beta systemd-journald[89]: Runtime Journal (/run/log/journal/a26205ecf0bf43b89de1b32d9f2e8613) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> beta # [805635.498759] beta systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> beta # [805635.510340] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> beta # [805635.523904] beta systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [805635.524711] beta systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [805635.525360] beta systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [805635.567273] beta systemd-journald[89]: Time spent on flushing to /var/log/journal/a26205ecf0bf43b89de1b32d9f2e8613 is 2.201ms for 7 entries.
container-test-run-data-mesher> beta # [805635.567273] beta systemd-journald[89]: System Journal (/var/log/journal/a26205ecf0bf43b89de1b32d9f2e8613) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [805635.588278] beta systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [805635.503498] gamma systemd-journald[90]: Journal started
container-test-run-data-mesher> gamma # [805635.503554] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/56364aa97e7d42ee9ad128c127d2eaa3) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> gamma # [805635.506360] gamma systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> gamma # [805635.517843] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> gamma # [805635.560496] gamma systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [805635.589022] beta systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [805635.561637] gamma systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [805635.589164] beta systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [805635.562276] gamma systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [805635.590003] beta systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> beta # [805635.590051] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [805635.590964] beta systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [805635.569513] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/56364aa97e7d42ee9ad128c127d2eaa3 is 2.037ms for 7 entries.
container-test-run-data-mesher> gamma # [805635.569513] gamma systemd-journald[90]: System Journal (/var/log/journal/56364aa97e7d42ee9ad128c127d2eaa3) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> gamma # [805635.593301] gamma systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> beta # [805635.591002] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [805635.593918] gamma systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> beta # [805635.592554] beta systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> gamma # [805635.595004] gamma systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [805635.593428] beta systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> gamma # [805635.595138] gamma systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> beta # [805635.610315] beta systemd-tmpfiles[160]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [805635.595987] gamma systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> beta # [805635.610533] beta systemd-tmpfiles[160]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [805635.596063] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [805635.610679] beta systemd-tmpfiles[160]: fchmod() of /var/log/journal/a26205ecf0bf43b89de1b32d9f2e8613 failed: Operation not permitted
container-test-run-data-mesher> gamma # [805635.596880] gamma systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> beta # [805635.610908] beta systemd-tmpfiles[160]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [805635.597653] gamma systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> beta # [805635.612401] beta systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [805635.597691] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> beta # [805635.613495] beta systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [805635.615457] gamma systemd-tmpfiles[161]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [805635.614193] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [805635.615667] gamma systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [805635.628267] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> gamma # [805635.615812] gamma systemd-tmpfiles[161]: fchmod() of /var/log/journal/56364aa97e7d42ee9ad128c127d2eaa3 failed: Operation not permitted
container-test-run-data-mesher> beta # [805635.633379] beta systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> gamma # [805635.616142] gamma systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [805635.634496] beta systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [805635.617784] gamma systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> beta # [805635.645335] beta systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [805635.618981] gamma systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> beta # [805635.668707] beta systemd[1]: Finished Firewall.
container-test-run-data-mesher> gamma # [805635.619825] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> beta # [805635.668860] beta systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [805635.633494] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> beta # [805635.669083] beta systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [805635.642462] gamma systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> beta # [805635.670138] beta systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [805635.643626] gamma systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> beta # [805635.708051] beta systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [805635.654215] gamma systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> beta # [805636.425892] beta systemd-networkd[206]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [805635.673962] gamma systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [805636.425976] beta systemd-networkd[206]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [805635.674148] gamma systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> beta # [805636.435255] beta systemd-networkd[206]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> gamma # [805635.674421] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> beta # [805636.435422] beta systemd-networkd[206]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> gamma # [805635.675671] gamma systemd[1]: Starting Network Management...
container-test-run-data-mesher> beta # [805636.435574] beta systemd-networkd[206]: lo: Link UP
container-test-run-data-mesher> gamma # [805635.708795] gamma systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> beta # [805636.435578] beta systemd-networkd[206]: lo: Gained carrier
container-test-run-data-mesher> gamma # [805636.402575] gamma systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [805636.435743] beta systemd-networkd[206]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> gamma # [805636.402665] gamma systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [805636.436186] beta systemd[1]: Started Network Management.
container-test-run-data-mesher> gamma # [805636.413859] gamma systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> beta # [805636.448379] beta systemd-networkd[206]: eth1: Link UP
container-test-run-data-mesher> gamma # [805636.414029] gamma systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> beta # [805636.448658] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> gamma # [805636.414193] gamma systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> beta # [805636.448991] beta systemd-networkd[206]: eth1: Gained carrier
container-test-run-data-mesher> gamma # [805636.414197] gamma systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> beta # [805636.489935] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [805636.414380] gamma systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [805636.491171] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> gamma # [805636.414834] gamma systemd[1]: Started Network Management.
container-test-run-data-mesher> gamma # [805636.414864] gamma systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> gamma # [805636.415219] gamma systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> gamma # [805636.416078] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> gamma # [805636.457189] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> gamma # [805636.511652] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [805636.775222] gamma systemd-resolved[138]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [805636.775234] gamma systemd-resolved[138]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [805636.775237] gamma systemd-resolved[138]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [805636.775274] gamma systemd-resolved[138]: 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 # [805636.797110] gamma systemd-resolved[138]: Using system hostname 'gamma'.
container-test-run-data-mesher> gamma # [805636.798468] gamma systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [805636.798597] gamma systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [805636.798720] gamma systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> alpha # [805636.798826] alpha systemd-resolved[135]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [805636.798810] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [805636.798836] alpha systemd-resolved[135]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [805636.798857] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [805636.798839] alpha systemd-resolved[135]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [805636.798894] gamma systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [805636.798875] alpha systemd-resolved[135]: 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 # [805636.799124] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> alpha # [805636.820614] alpha systemd-resolved[135]: Using system hostname 'alpha'.
container-test-run-data-mesher> gamma # [805636.799361] gamma systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [805636.821994] alpha systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [805636.799585] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> alpha # [805636.822130] alpha systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [805636.799636] gamma systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> alpha # [805636.822247] alpha systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [805636.799739] gamma systemd[1]: Reached target Basic System.
container-test-run-data-mesher> alpha # [805636.822342] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [805636.802327] gamma systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> alpha # [805636.822400] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> gamma # [805636.840803] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> alpha # [805636.822438] alpha systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> gamma # [805636.842192] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> alpha # [805636.822668] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> gamma # [805636.844598] gamma systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [805636.822885] alpha systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> gamma # [805636.863733] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [805636.823102] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [805637.064791] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [805636.823152] alpha systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> gamma # [805637.065214] gamma nsncd[216]: Sep 13 15:14:17.050 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [805636.823230] alpha systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [805637.064864] gamma systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [805636.841522] alpha systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [805637.064935] gamma systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> alpha # [805636.842856] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> alpha # [805636.844189] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> alpha # [805636.846301] alpha systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [805636.863738] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [805637.064809] alpha nsncd[215]: Sep 13 15:14:17.050 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [805637.064779] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [805637.064840] alpha systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [805637.064902] alpha systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> beta # [805636.772070] beta systemd-resolved[122]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [805636.772080] beta systemd-resolved[122]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> beta # [805636.772084] beta systemd-resolved[122]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> beta # [805636.772118] beta systemd-resolved[122]: 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 # [805636.794154] beta systemd-resolved[122]: Using system hostname 'beta'.
container-test-run-data-mesher> beta # [805636.795526] beta systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> beta # [805636.795633] beta systemd[1]: Reached target Network.
container-test-run-data-mesher> beta # [805636.795721] beta systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> beta # [805636.795786] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [805636.795822] beta systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> beta # [805636.795845] beta systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [805636.796013] beta systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> beta # [805636.796172] beta systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> beta # [805636.796307] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> beta # [805636.796338] beta systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [805636.796396] beta systemd[1]: Reached target Basic System.
container-test-run-data-mesher> beta # [805636.797973] beta systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> beta # [805636.798890] beta systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> beta # [805636.799983] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> beta # [805636.801639] beta systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> beta # [805636.858313] beta systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> beta # [805637.078111] beta systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> beta # [805637.078222] beta systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> beta # [805637.078390] beta nsncd[214]: Sep 13 15:14:17.064 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> beta # [805637.078804] beta systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> gamma # [805637.069258] gamma systemd[1]: Starting User Login Management...
container-test-run-data-mesher> gamma # [805637.070833] gamma systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [805637.081923] gamma systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> gamma # [805637.082992] gamma systemd[1]: Started Console Getty.
container-test-run-data-mesher> gamma # [805637.083032] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> gamma # [805637.083053] gamma systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [805637.314476] gamma dbus-broker-launch[217]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> gamma # [805637.315425] gamma dbus-broker-launch[217]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> gamma # [805637.315425] gamma dbus-broker-launch[217]: Invalid user-name in /nix/store/g9sjkbna93wz5x7sl4sxdpqwza9iwxwz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [805637.080071] beta systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [805637.069089] alpha systemd[1]: Starting User Login Management...
container-test-run-data-mesher> beta # [805637.080979] beta systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> alpha # [805637.070956] alpha systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> beta # [805637.093100] beta systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> alpha # [805637.081971] alpha systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> beta # [805637.094422] beta systemd[1]: Started Console Getty.
container-test-run-data-mesher> alpha # [805637.082997] alpha systemd[1]: Started Console Getty.
container-test-run-data-mesher> beta # [805637.094483] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> alpha # [805637.083044] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> beta # [805637.094508] beta systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> alpha # [805637.083067] alpha systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> beta # [805637.280602] beta dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> alpha # [805637.293431] alpha dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [805637.282136] beta dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> alpha # [805637.294589] alpha dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> beta # [805637.282136] beta dbus-broker-launch[215]: Invalid user-name in /nix/store/g9sjkbna93wz5x7sl4sxdpqwza9iwxwz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> alpha # [805637.294589] alpha dbus-broker-launch[216]: Invalid user-name in /nix/store/g9sjkbna93wz5x7sl4sxdpqwza9iwxwz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [805637.282652] beta systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [805637.295125] alpha systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> beta # [805637.290218] beta dbus-broker-launch[215]: Ready
container-test-run-data-mesher> alpha # [805637.303675] alpha dbus-broker-launch[216]: Ready
container-test-run-data-mesher> gamma # [805637.315758] gamma systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> gamma # [805637.324023] gamma dbus-broker-launch[217]: Ready
container-test-run-data-mesher> beta # [805637.568240] beta systemd-networkd[206]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [805637.777990] beta data-mesher[212]: time=2026-09-13T15:14:17.763Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> beta # [805637.779059] beta data-mesher[212]: time=2026-09-13T15:14:17.764Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805637.779111] beta data-mesher[212]: time=2026-09-13T15:14:17.764Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> beta # [805637.781237] beta data-mesher[212]: time=2026-09-13T15:14:17.767Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [805637.781353] beta data-mesher[212]: time=2026-09-13T15:14:17.767Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [805637.785104] beta data-mesher[212]: time=2026-09-13T15:14:17.770Z level=INFO msg="libp2p host created" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC 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 # [805637.785141] beta data-mesher[212]: time=2026-09-13T15:14:17.770Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> beta # [805637.785141] beta data-mesher[212]: time=2026-09-13T15:14:17.770Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> beta # [805637.785141] beta data-mesher[212]: time=2026-09-13T15:14:17.770Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> beta # [805637.785141] beta data-mesher[212]: time=2026-09-13T15:14:17.770Z level=INFO msg="starting server"
container-test-run-data-mesher> beta # [805637.785248] beta data-mesher[212]: time=2026-09-13T15:14:17.771Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> beta # [805637.785345] beta data-mesher[212]: time=2026-09-13T15:14:17.771Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> beta # [805637.785384] beta data-mesher[212]: time=2026-09-13T15:14:17.771Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> beta # [805637.792527] beta data-mesher[212]: time=2026-09-13T15:14:17.778Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> alpha # [805637.761353] alpha data-mesher[213]: time=2026-09-13T15:14:17.747Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> alpha # [805637.762428] alpha data-mesher[213]: time=2026-09-13T15:14:17.748Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805637.762428] alpha data-mesher[213]: time=2026-09-13T15:14:17.748Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> alpha # [805637.770620] alpha data-mesher[213]: time=2026-09-13T15:14:17.756Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> alpha # [805637.770743] alpha data-mesher[213]: time=2026-09-13T15:14:17.756Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> alpha # [805637.774927] alpha data-mesher[213]: time=2026-09-13T15:14:17.760Z level=INFO msg="libp2p host created" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg 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 # [805637.775008] alpha data-mesher[213]: time=2026-09-13T15:14:17.760Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> alpha # [805637.775008] alpha data-mesher[213]: time=2026-09-13T15:14:17.760Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> alpha # [805637.775008] alpha data-mesher[213]: time=2026-09-13T15:14:17.760Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> alpha # [805637.775008] alpha data-mesher[213]: time=2026-09-13T15:14:17.760Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [805637.775206] alpha data-mesher[213]: time=2026-09-13T15:14:17.760Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [805637.775206] alpha data-mesher[213]: time=2026-09-13T15:14:17.760Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [805637.775206] alpha data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [805637.793447] alpha data-mesher[213]: time=2026-09-13T15:14:17.779Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> alpha # [805637.937359] alpha systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha # [805638.000279] alpha systemd-logind[232]: New seat seat0.
container-test-run-data-mesher> alpha # [805638.000524] alpha systemd[1]: Started User Login Management.
container-test-run-data-mesher> alpha # [805638.002767] alpha systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [805637.762192] gamma data-mesher[213]: time=2026-09-13T15:14:17.747Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> gamma # [805637.763267] gamma data-mesher[213]: time=2026-09-13T15:14:17.749Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> gamma # [805637.763267] gamma data-mesher[213]: time=2026-09-13T15:14:17.749Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> gamma # [805637.770708] gamma data-mesher[213]: time=2026-09-13T15:14:17.756Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> gamma # [805637.770829] gamma data-mesher[213]: time=2026-09-13T15:14:17.756Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> gamma # [805637.775321] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="libp2p host created" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC 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 # [805637.775402] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> gamma # [805637.775402] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> gamma # [805637.775402] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> gamma # [805637.775402] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="starting server"
container-test-run-data-mesher> gamma # [805637.775514] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> gamma # [805637.775514] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> gamma # [805637.775565] gamma data-mesher[213]: time=2026-09-13T15:14:17.761Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> gamma # [805637.937511] gamma systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> gamma # [805637.986709] gamma systemd-logind[232]: New seat seat0.
container-test-run-data-mesher> gamma # [805637.986894] gamma systemd[1]: Started User Login Management.
container-test-run-data-mesher> gamma # [805637.988035] gamma systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [805638.009011] gamma systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> gamma # [805638.009245] gamma systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> alpha # [805638.019367] alpha systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> alpha # [805638.019457] alpha systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> beta # [805638.001413] beta systemd-logind[231]: New seat seat0.
container-test-run-data-mesher> beta # [805638.001596] beta systemd[1]: Started User Login Management.
container-test-run-data-mesher> beta # [805638.003067] beta systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> beta # [805638.017689] beta systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> beta # [805638.017927] beta systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> beta # [805642.793363] beta data-mesher[212]: time=2026-09-13T15:14:22.779Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> alpha # [805642.781077] alpha data-mesher[213]: time=2026-09-13T15:14:22.766Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> gamma # [805642.780439] gamma data-mesher[213]: time=2026-09-13T15:14:22.766Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> gamma # [805642.791909] gamma data-mesher[213]: time=2026-09-13T15:14:22.777Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> alpha: still waiting for container 'alpha' to reach ready state...
container-test-run-data-mesher> gamma # [805647.775781] gamma data-mesher[213]: time=2026-09-13T15:14:27.761Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [805647.776484] gamma data-mesher[213]: time=2026-09-13T15:14:27.761Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [805647.776708] gamma data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805647.776708] gamma data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [805647.776853] gamma data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="server started"
container-test-run-data-mesher> beta # [805647.776105] beta data-mesher[212]: time=2026-09-13T15:14:27.761Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805647.775379] alpha data-mesher[213]: time=2026-09-13T15:14:27.761Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> beta # [805647.776105] beta data-mesher[212]: time=2026-09-13T15:14:27.761Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805647.776975] gamma data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> beta # [805647.785967] beta data-mesher[212]: time=2026-09-13T15:14:27.771Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [805647.777079] gamma systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> beta # [805647.786051] beta data-mesher[212]: time=2026-09-13T15:14:27.771Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [805647.777597] gamma systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [805647.786709] beta data-mesher[212]: time=2026-09-13T15:14:27.772Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805647.776108] alpha data-mesher[213]: time=2026-09-13T15:14:27.761Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [805647.777899] gamma systemd[1]: Startup finished in 12.708s.
container-test-run-data-mesher> alpha # [805647.776335] alpha data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [805647.786709] beta data-mesher[212]: time=2026-09-13T15:14:27.772Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> alpha # [805647.776335] alpha data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805647.786819] beta data-mesher[212]: time=2026-09-13T15:14:27.772Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [805647.776476] alpha data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> beta # [805647.786876] beta data-mesher[212]: time=2026-09-13T15:14:27.772Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [805647.776476] alpha data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [805647.787059] beta systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [805647.776476] alpha data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [805647.776618] alpha data-mesher[213]: time=2026-09-13T15:14:27.762Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [805647.776812] alpha systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [805647.777332] alpha systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> alpha # [805647.777744] alpha systemd[1]: Startup finished in 12.700s.
container-test-run-data-mesher> alpha # [805647.786480] alpha data-mesher[213]: time=2026-09-13T15:14:27.772Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [805647.786588] alpha data-mesher[213]: time=2026-09-13T15:14:27.772Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805647.787574] beta systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [805647.787890] beta systemd[1]: Startup finished in 12.718s.
container-test-run-data-mesher> alpha: (finished: waiting for unit data-mesher.service, in 13.68 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/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-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/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.07 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/q7qp944cmfslmghi1x4lzalffrb8x0l4-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/q7qp944cmfslmghi1x4lzalffrb8x0l4-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> alpha # [805648.306397] alpha data-mesher[213]: time=2026-09-13T15:14:28.292Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> gamma # [805652.777392] gamma data-mesher[213]: time=2026-09-13T15:14:32.763Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805652.777864] gamma data-mesher[213]: time=2026-09-13T15:14:32.763Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> beta # [805652.778160] beta data-mesher[212]: time=2026-09-13T15:14:32.763Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [805652.778156] alpha data-mesher[213]: time=2026-09-13T15:14:32.763Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805652.778392] gamma data-mesher[213]: time=2026-09-13T15:14:32.764Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805652.778160] beta data-mesher[212]: time=2026-09-13T15:14:32.763Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [805652.778562] alpha data-mesher[213]: time=2026-09-13T15:14:32.764Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [805652.778392] gamma data-mesher[213]: time=2026-09-13T15:14:32.764Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [805652.779421] alpha data-mesher[213]: time=2026-09-13T15:14:32.765Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805652.779050] beta data-mesher[212]: time=2026-09-13T15:14:32.764Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805652.779421] alpha data-mesher[213]: time=2026-09-13T15:14:32.765Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [805652.778480] gamma data-mesher[213]: time=2026-09-13T15:14:32.764Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [805652.779527] alpha data-mesher[213]: time=2026-09-13T15:14:32.765Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [805652.779138] beta data-mesher[212]: time=2026-09-13T15:14:32.764Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805652.779651] alpha data-mesher[213]: time=2026-09-13T15:14:32.765Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> beta # [805652.779138] beta data-mesher[212]: time=2026-09-13T15:14:32.764Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file
container-test-run-data-mesher> alpha # [805652.781810] alpha data-mesher[213]: time=2026-09-13T15:14:32.767Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> beta # [805652.779246] beta data-mesher[212]: time=2026-09-13T15:14:32.764Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> alpha # [805652.788146] alpha data-mesher[213]: time=2026-09-13T15:14:32.773Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805652.779302] beta data-mesher[212]: time=2026-09-13T15:14:32.765Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-13 15:14:28.25 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805652.788146] alpha data-mesher[213]: time=2026-09-13T15:14:32.773Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805652.787440] beta data-mesher[212]: time=2026-09-13T15:14:32.773Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [805652.787556] beta data-mesher[212]: time=2026-09-13T15:14:32.773Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805652.788326] beta data-mesher[212]: time=2026-09-13T15:14:32.774Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [805652.788366] beta data-mesher[212]: time=2026-09-13T15:14:32.774Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file
container-test-run-data-mesher> beta # [805652.788366] beta data-mesher[212]: time=2026-09-13T15:14:32.774Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805652.788418] beta data-mesher[212]: time=2026-09-13T15:14:32.774Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [805652.851343] beta data-mesher[212]: time=2026-09-13T15:14:32.837Z level=INFO msg="download complete" name=test_file signed_at="2026-09-13 15:14:28.25 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=72.098954ms
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 # [805657.779949] gamma data-mesher[213]: time=2026-09-13T15:14:37.765Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805657.779949] gamma data-mesher[213]: time=2026-09-13T15:14:37.765Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> beta # [805657.780224] beta data-mesher[212]: time=2026-09-13T15:14:37.766Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> gamma # [805657.780480] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [805657.780224] beta data-mesher[212]: time=2026-09-13T15:14:37.766Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [805657.789262] beta data-mesher[212]: time=2026-09-13T15:14:37.775Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805657.780480] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805657.779804] alpha data-mesher[213]: time=2026-09-13T15:14:37.765Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805657.780480] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file
container-test-run-data-mesher> alpha # [805657.779804] alpha data-mesher[213]: time=2026-09-13T15:14:37.765Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> gamma # [805657.780480] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [805657.789262] beta data-mesher[212]: time=2026-09-13T15:14:37.775Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> alpha # [805657.780780] alpha data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [805657.780780] alpha data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> gamma # [805657.780480] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=DEBUG msg="new file detected" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file name=test_file
container-test-run-data-mesher> alpha # [805657.780780] alpha data-mesher[213]: time=2026-09-13T15:14:37.766Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [805657.789949] beta data-mesher[212]: time=2026-09-13T15:14:37.775Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [805657.789949] beta data-mesher[212]: time=2026-09-13T15:14:37.775Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> gamma # [805657.780480] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [805657.781094] alpha data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> gamma # [805657.780667] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [805657.790082] beta data-mesher[212]: time=2026-09-13T15:14:37.775Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [805657.780667] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> alpha # [805657.782803] alpha data-mesher[213]: time=2026-09-13T15:14:37.768Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> gamma # [805657.780667] gamma data-mesher[213]: time=2026-09-13T15:14:37.766Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-13 15:14:28.25 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805657.790263] gamma data-mesher[213]: time=2026-09-13T15:14:37.775Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [805657.790263] gamma data-mesher[213]: time=2026-09-13T15:14:37.775Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [805657.790263] gamma data-mesher[213]: time=2026-09-13T15:14:37.775Z level=DEBUG msg="new file detected" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file name=test_file
container-test-run-data-mesher> gamma # [805657.820384] gamma data-mesher[213]: time=2026-09-13T15:14:37.806Z level=INFO msg="download complete" name=test_file signed_at="2026-09-13 15:14:28.25 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=39.769268ms
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.05 seconds)
container-test-run-data-mesher> beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.06 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> beta # [805658.471022] beta data-mesher[212]: time=2026-09-13T15:14:38.456Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta # [805662.782160] beta data-mesher[212]: time=2026-09-13T15:14:42.767Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805662.781368] gamma data-mesher[213]: time=2026-09-13T15:14:42.767Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [805662.782160] beta data-mesher[212]: time=2026-09-13T15:14:42.767Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805662.781368] gamma data-mesher[213]: time=2026-09-13T15:14:42.767Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805662.790466] beta data-mesher[212]: time=2026-09-13T15:14:42.776Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805662.782188] gamma data-mesher[213]: time=2026-09-13T15:14:42.767Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [805662.790569] beta data-mesher[212]: time=2026-09-13T15:14:42.776Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [805662.782246] gamma data-mesher[213]: time=2026-09-13T15:14:42.768Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> alpha # [805662.781484] alpha data-mesher[213]: time=2026-09-13T15:14:42.767Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805662.782304] gamma data-mesher[213]: time=2026-09-13T15:14:42.768Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [805662.837963] beta data-mesher[212]: time=2026-09-13T15:14:42.823Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [805662.782164] alpha data-mesher[213]: time=2026-09-13T15:14:42.767Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> beta # [805662.837963] beta data-mesher[212]: time=2026-09-13T15:14:42.823Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805662.838162] beta data-mesher[212]: time=2026-09-13T15:14:42.823Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [805662.782164] alpha data-mesher[213]: time=2026-09-13T15:14:42.767Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [805662.782164] alpha data-mesher[213]: time=2026-09-13T15:14:42.767Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [805662.782387] alpha data-mesher[213]: time=2026-09-13T15:14:42.768Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [805662.790849] alpha data-mesher[213]: time=2026-09-13T15:14:42.776Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [805662.790849] alpha data-mesher[213]: time=2026-09-13T15:14:42.776Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [805662.837671] alpha data-mesher[213]: time=2026-09-13T15:14:42.823Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true
container-test-run-data-mesher> alpha # [805662.837671] alpha data-mesher[213]: time=2026-09-13T15:14:42.823Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [805662.837843] alpha data-mesher[213]: time=2026-09-13T15:14:42.823Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=false
container-test-run-data-mesher> alpha # [805662.837972] alpha data-mesher[213]: time=2026-09-13T15:14:42.823Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 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> beta # [805667.839070] beta data-mesher[212]: time=2026-09-13T15:14:47.824Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [805667.839896] beta data-mesher[212]: time=2026-09-13T15:14:47.824Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805667.839896] beta data-mesher[212]: time=2026-09-13T15:14:47.825Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [805667.840047] beta data-mesher[212]: time=2026-09-13T15:14:47.825Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805667.840047] beta data-mesher[212]: time=2026-09-13T15:14:47.825Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [805667.783410] alpha data-mesher[213]: time=2026-09-13T15:14:47.768Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> gamma # [805667.782576] gamma data-mesher[213]: time=2026-09-13T15:14:47.768Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [805667.783410] alpha data-mesher[213]: time=2026-09-13T15:14:47.769Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> gamma # [805667.782576] gamma data-mesher[213]: time=2026-09-13T15:14:47.768Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> alpha # [805667.838627] alpha data-mesher[213]: time=2026-09-13T15:14:47.824Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805667.783411] gamma data-mesher[213]: time=2026-09-13T15:14:47.769Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805667.808260] gamma data-mesher[213]: time=2026-09-13T15:14:47.794Z level=DEBUG msg="imported tombstone" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file written=true
container-test-run-data-mesher> gamma # [805667.808260] gamma data-mesher[213]: time=2026-09-13T15:14:47.794Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> alpha # [805667.838720] alpha data-mesher[213]: time=2026-09-13T15:14:47.824Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> alpha # [805667.839335] alpha data-mesher[213]: time=2026-09-13T15:14:47.825Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [805667.839335] alpha data-mesher[213]: time=2026-09-13T15:14:47.825Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> alpha # [805667.839462] alpha data-mesher[213]: time=2026-09-13T15:14:47.825Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [805667.839550] alpha data-mesher[213]: time=2026-09-13T15:14:47.825Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [805667.839550] alpha data-mesher[213]: time=2026-09-13T15:14:47.825Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [805667.808409] gamma data-mesher[213]: time=2026-09-13T15:14:47.794Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [805667.839233] gamma data-mesher[213]: time=2026-09-13T15:14:47.824Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805667.839233] gamma data-mesher[213]: time=2026-09-13T15:14:47.824Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)
container-test-run-data-mesher> alpha: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub
container-test-run-data-mesher> alpha: (finished: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-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/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-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/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.02 seconds)
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [805668.597703] alpha data-mesher[213]: time=2026-09-13T15:14:48.583Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=204
container-test-run-data-mesher> beta # [805672.809060] beta data-mesher[212]: time=2026-09-13T15:14:52.794Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [805672.809060] beta data-mesher[212]: time=2026-09-13T15:14:52.794Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [805672.840740] beta data-mesher[212]: time=2026-09-13T15:14:52.826Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [805672.840904] beta data-mesher[212]: time=2026-09-13T15:14:52.826Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805672.841666] beta data-mesher[212]: time=2026-09-13T15:14:52.827Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [805672.841989] beta data-mesher[212]: time=2026-09-13T15:14:52.827Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> beta # [805672.841989] beta data-mesher[212]: time=2026-09-13T15:14:52.827Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [805672.842137] beta data-mesher[212]: time=2026-09-13T15:14:52.827Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [805672.842137] beta data-mesher[212]: time=2026-09-13T15:14:52.827Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> beta # [805672.842240] beta data-mesher[212]: time=2026-09-13T15:14:52.827Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-13 15:14:48.581 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [805672.850177] beta data-mesher[212]: time=2026-09-13T15:14:52.835Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-13 15:14:48.581 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=8.058992ms
container-test-run-data-mesher> alpha # [805672.840170] alpha data-mesher[213]: time=2026-09-13T15:14:52.825Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [805672.840170] alpha data-mesher[213]: time=2026-09-13T15:14:52.825Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> alpha # [805672.841304] alpha data-mesher[213]: time=2026-09-13T15:14:52.827Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [805672.841304] alpha data-mesher[213]: time=2026-09-13T15:14:52.827Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [805672.841880] alpha data-mesher[213]: time=2026-09-13T15:14:52.827Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [805672.841880] alpha data-mesher[213]: time=2026-09-13T15:14:52.827Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> alpha # [805672.841880] alpha data-mesher[213]: time=2026-09-13T15:14:52.827Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [805672.842185] alpha data-mesher[213]: time=2026-09-13T15:14:52.827Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [805672.842628] alpha data-mesher[213]: time=2026-09-13T15:14:52.828Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [805672.848642] alpha data-mesher[213]: time=2026-09-13T15:14:52.834Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [805672.848805] alpha data-mesher[213]: time=2026-09-13T15:14:52.834Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma # [805672.808433] gamma data-mesher[213]: time=2026-09-13T15:14:52.794Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [805672.809191] gamma data-mesher[213]: time=2026-09-13T15:14:52.794Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [805672.809459] gamma data-mesher[213]: time=2026-09-13T15:14:52.795Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [805672.809459] gamma data-mesher[213]: time=2026-09-13T15:14:52.795Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [805672.809459] gamma data-mesher[213]: time=2026-09-13T15:14:52.795Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [805672.841618] gamma data-mesher[213]: time=2026-09-13T15:14:52.826Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805672.841618] gamma data-mesher[213]: time=2026-09-13T15:14:52.826Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805672.841618] gamma data-mesher[213]: time=2026-09-13T15:14:52.827Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma # [805672.841618] gamma data-mesher[213]: time=2026-09-13T15:14:52.827Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma # [805672.841935] gamma data-mesher[213]: time=2026-09-13T15:14:52.827Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-13 15:14:48.581 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [805672.849811] gamma data-mesher[213]: time=2026-09-13T15:14:52.835Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-13 15:14:48.581 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=8.185433ms
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 5.05 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, 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/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub
container-test-run-data-mesher> Error: failed to update file: 403 Forbidden, signer Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE= is not authorized for this file test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub, in 0.02 seconds)
container-test-run-data-mesher> (finished: run the VM test script, in 39.19 seconds)
container-test-run-data-mesher> test script finished in 39.28s
container-test-run-data-mesher> cleanup
container-test-run-data-mesher> kill NspawnMachine (pid 53)
container-test-run-data-mesher> kill NspawnMachine (pid 54)
container-test-run-data-mesher> Container alpha terminated by signal KILL.
container-test-run-data-mesher> kill NspawnMachine (pid 55)
container-test-run-data-mesher> Container beta terminated by signal KILL.
container-test-run-data-mesher> Container gamma terminated by signal KILL.
container-test-run-data-mesher> (finished: cleanup, in 0.49 seconds)

upload niks3: ok