these 24 derivations will be built:
  /nix/store/bk3jpickph93vgfgzmnksck2gy11vika-dbus-1.drv
  /nix/store/3lqpk12jfg730lgq4l5w866k77pxqlqa-X-Restart-Triggers-dbus-broker.drv
  /nix/store/1q42ypqqgnychbkm2a57fm7ai0wc128n-unit-dbus-broker.service.drv
  /nix/store/4qbz3gd76dypfyqiw1jrwqcaf106qvw3-system-units.drv
  /nix/store/nn0f26pacb2can44sqpdhg6fv1a3pkw3-unit-dbus-broker.service.drv
  /nix/store/zma2alpdr83qibrkc01ll7xpvhi4jgrz-user-units.drv
  /nix/store/0c48lq4rp39qb29if4kfgk50w0zcgagq-etc.drv
  /nix/store/5579yz2i5n5avibckkg1fnyiqmy9z9pz-activate.drv
  /nix/store/25ayzgylq0k5p87jfbhz727a8s9vj6yk-nixos-system-gamma-test.drv
  /nix/store/hs7xw62dga3apajv2zv0mf5y50qb62q8-run-gamma-nspawn.drv
  /nix/store/nylpqnzlzb3jxfaqfrc255y293xkqn39-system-units.drv
  /nix/store/641lyskqyk9q82yfgsrapg9jpl4ki2s4-etc.drv
  /nix/store/9yyqbaa6q9fy1wjb53hjd6g27j40a8ni-activate.drv
  /nix/store/vg4xnk4h34nzxnfrbgc4nhj620g2irxg-dry-activate.drv
  /nix/store/fgd3bp86jqgbjrp7akgw5ah5y0b82kga-nixos-system-alpha-test.drv
  /nix/store/ilxfpa1f9vranzpyczck1vw56famq917-run-alpha-nspawn.drv
  /nix/store/lmndmy1qds36kx24fnw897jjzgcrhppk-system-units.drv
  /nix/store/j786kjaldmm3sdp11siq5gbyxdjf1g0s-etc.drv
  /nix/store/735wlpdzfhaccdh6wav2k0325jvgrsdq-activate.drv
  /nix/store/dw90gchh40qf71cd05kg7s4i6hviw3qz-nixos-system-beta-test.drv
  /nix/store/vvv6ry240iai6snykhcph511f5as67sv-run-beta-nspawn.drv
  /nix/store/f190gj9hr9vxsscqcxzhaivfpnk058d4-driverConfiguration.json.drv
  /nix/store/0fb2vjhg7y6r08acqp0bcp0b7cx49qda-nixos-test-driver-data-mesher.drv
  /nix/store/7n5bvkkaczjrd1dcnrfzif7azpyix9kh-container-test-run-data-mesher.drv
building '/nix/store/vg4xnk4h34nzxnfrbgc4nhj620g2irxg-dry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/bk3jpickph93vgfgzmnksck2gy11vika-dbus-1.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vg4xnk4h34nzxnfrbgc4nhj620g2irxg-dry-activate.drv'
building '/nix/store/bk3jpickph93vgfgzmnksck2gy11vika-dbus-1.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/3lqpk12jfg730lgq4l5w866k77pxqlqa-X-Restart-Triggers-dbus-broker.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/3lqpk12jfg730lgq4l5w866k77pxqlqa-X-Restart-Triggers-dbus-broker.drv'
building '/nix/store/1q42ypqqgnychbkm2a57fm7ai0wc128n-unit-dbus-broker.service.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nn0f26pacb2can44sqpdhg6fv1a3pkw3-unit-dbus-broker.service.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/nn0f26pacb2can44sqpdhg6fv1a3pkw3-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/1q42ypqqgnychbkm2a57fm7ai0wc128n-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/4qbz3gd76dypfyqiw1jrwqcaf106qvw3-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/lmndmy1qds36kx24fnw897jjzgcrhppk-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/nylpqnzlzb3jxfaqfrc255y293xkqn39-system-units.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/zma2alpdr83qibrkc01ll7xpvhi4jgrz-user-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/4qbz3gd76dypfyqiw1jrwqcaf106qvw3-system-units.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/nylpqnzlzb3jxfaqfrc255y293xkqn39-system-units.drv'
building '/nix/store/zma2alpdr83qibrkc01ll7xpvhi4jgrz-user-units.drv'
building '/nix/store/lmndmy1qds36kx24fnw897jjzgcrhppk-system-units.drv'
building '/nix/store/0c48lq4rp39qb29if4kfgk50w0zcgagq-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0c48lq4rp39qb29if4kfgk50w0zcgagq-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/641lyskqyk9q82yfgsrapg9jpl4ki2s4-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/641lyskqyk9q82yfgsrapg9jpl4ki2s4-etc.drv'
building '/nix/store/j786kjaldmm3sdp11siq5gbyxdjf1g0s-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/5579yz2i5n5avibckkg1fnyiqmy9z9pz-activate.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/9yyqbaa6q9fy1wjb53hjd6g27j40a8ni-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/5579yz2i5n5avibckkg1fnyiqmy9z9pz-activate.drv'
building '/nix/store/9yyqbaa6q9fy1wjb53hjd6g27j40a8ni-activate.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/fgd3bp86jqgbjrp7akgw5ah5y0b82kga-nixos-system-alpha-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/j786kjaldmm3sdp11siq5gbyxdjf1g0s-etc.drv'
building '/nix/store/25ayzgylq0k5p87jfbhz727a8s9vj6yk-nixos-system-gamma-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/fgd3bp86jqgbjrp7akgw5ah5y0b82kga-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/25ayzgylq0k5p87jfbhz727a8s9vj6yk-nixos-system-gamma-test.drv'
nixos-system-gamma-test> structuredAttrs is enabled
building '/nix/store/hs7xw62dga3apajv2zv0mf5y50qb62q8-run-gamma-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/ilxfpa1f9vranzpyczck1vw56famq917-run-alpha-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/hs7xw62dga3apajv2zv0mf5y50qb62q8-run-gamma-nspawn.drv'
building '/nix/store/735wlpdzfhaccdh6wav2k0325jvgrsdq-activate.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/735wlpdzfhaccdh6wav2k0325jvgrsdq-activate.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/ilxfpa1f9vranzpyczck1vw56famq917-run-alpha-nspawn.drv'
building '/nix/store/dw90gchh40qf71cd05kg7s4i6hviw3qz-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/dw90gchh40qf71cd05kg7s4i6hviw3qz-nixos-system-beta-test.drv'
nixos-system-beta-test> structuredAttrs is enabled
building '/nix/store/vvv6ry240iai6snykhcph511f5as67sv-run-beta-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vvv6ry240iai6snykhcph511f5as67sv-run-beta-nspawn.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/f190gj9hr9vxsscqcxzhaivfpnk058d4-driverConfiguration.json.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/f190gj9hr9vxsscqcxzhaivfpnk058d4-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/0fb2vjhg7y6r08acqp0bcp0b7cx49qda-nixos-test-driver-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0fb2vjhg7y6r08acqp0bcp0b7cx49qda-nixos-test-driver-data-mesher.drv'
nixos-test-driver-data-mesher> Running type check (enable/disable: config.skipTypeCheck)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
nixos-test-driver-data-mesher> All checks passed!
nixos-test-driver-data-mesher> Linting test script (enable/disable: config.skipLint)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
nixos-test-driver-data-mesher> All checks passed!
building '/nix/store/7n5bvkkaczjrd1dcnrfzif7azpyix9kh-container-test-run-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/7n5bvkkaczjrd1dcnrfzif7azpyix9kh-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 seconds
container-test-run-data-mesher> run the VM test script
container-test-run-data-mesher> additionally exposed symbols:
container-test-run-data-mesher>     alpha, beta, gamma,
container-test-run-data-mesher>     vlan1,
container-test-run-data-mesher>     start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
container-test-run-data-mesher> start all VMs
container-test-run-data-mesher> alpha: systemd-nspawn running (pid 52)
container-test-run-data-mesher> 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: systemd-nspawn running (pid 57)
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(alpha): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(alpha): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(gamma): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(beta): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> ░ Spawning container alpha on /build/vm-state-alpha.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> ░ Spawning container gamma on /build/vm-state-gamma.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> ░ Spawning container beta on /build/vm-state-beta.
container-test-run-data-mesher> gamma # No journal files were found.
container-test-run-data-mesher> alpha # No journal files were found.
container-test-run-data-mesher> gamma # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> alpha # No journal 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> alpha # [4440977.829221] alpha systemd-journald[90]: Journal started
container-test-run-data-mesher> alpha # [4440977.829315] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/cd4d9a8619324bb1985e12c9a7f32c97) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> alpha # [4440977.838487] alpha systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> alpha # [4440977.850977] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> alpha # [4440977.865557] alpha systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> alpha # [4440977.866399] alpha systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> alpha # [4440977.867087] alpha systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> alpha # [4440977.873275] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/cd4d9a8619324bb1985e12c9a7f32c97 is 3.831ms for 7 entries.
container-test-run-data-mesher> alpha # [4440977.873275] alpha systemd-journald[90]: System Journal (/var/log/journal/cd4d9a8619324bb1985e12c9a7f32c97) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> alpha # [4440977.898673] alpha systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> alpha # [4440977.899432] alpha systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [4440977.830967] gamma systemd-journald[90]: Journal started
container-test-run-data-mesher> alpha # [4440977.900649] alpha systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [4440977.831101] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/af23a242b2bf4ab08f200064613567ad) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> alpha # [4440977.900805] alpha systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [4440977.834263] gamma systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> alpha # [4440977.901605] alpha systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> gamma # [4440977.846889] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> alpha # [4440977.901650] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [4440977.861134] gamma systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> gamma # [4440977.861998] gamma systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> gamma # [4440977.863168] gamma systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> gamma # [4440977.869077] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/af23a242b2bf4ab08f200064613567ad is 2.637ms for 7 entries.
container-test-run-data-mesher> gamma # [4440977.869077] gamma systemd-journald[90]: System Journal (/var/log/journal/af23a242b2bf4ab08f200064613567ad) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> gamma # [4440977.898496] gamma systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> gamma # [4440977.899162] gamma systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [4440977.900401] gamma systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [4440977.900511] gamma systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [4440977.901376] gamma systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> gamma # [4440977.901424] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [4440977.902319] gamma systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [4440977.902985] gamma systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> gamma # [4440977.903016] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [4440977.917409] gamma systemd-tmpfiles[171]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [4440977.917594] gamma systemd-tmpfiles[171]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [4440977.917716] gamma systemd-tmpfiles[171]: fchmod() of /var/log/journal/af23a242b2bf4ab08f200064613567ad failed: Operation not permitted
container-test-run-data-mesher> gamma # [4440977.917896] gamma systemd-tmpfiles[171]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [4440977.924125] gamma systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [4440977.925160] gamma systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [4440977.925826] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [4440977.936444] gamma systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [4440977.937905] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> gamma # [4440977.943720] gamma systemd[1]: Finished Firewall.
container-test-run-data-mesher> gamma # [4440977.943942] gamma systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [4440977.902428] alpha systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [4440977.944521] gamma systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> alpha # [4440977.903059] alpha systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [4440977.903087] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [4440977.917296] alpha systemd-tmpfiles[164]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [4440977.917500] alpha systemd-tmpfiles[164]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [4440977.944799] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [4440977.945784] gamma systemd[1]: Starting Network Management...
container-test-run-data-mesher> beta # [4440977.789191] beta systemd-journald[91]: Journal started
container-test-run-data-mesher> alpha # [4440977.917632] alpha systemd-tmpfiles[164]: fchmod() of /var/log/journal/cd4d9a8619324bb1985e12c9a7f32c97 failed: Operation not permitted
container-test-run-data-mesher> beta # [4440977.789275] beta systemd-journald[91]: Runtime Journal (/run/log/journal/321548dbfd8643f5824ee6e9f14fd017) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> alpha # [4440977.917822] alpha systemd-tmpfiles[164]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [4440977.822019] beta systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> alpha # [4440977.924082] alpha systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [4440977.946498] gamma systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> beta # [4440977.840907] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> gamma # [4440977.954977] gamma systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> beta # [4440977.858874] beta systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> alpha # [4440977.925091] alpha systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> beta # [4440977.859757] beta systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> alpha # [4440977.926000] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> beta # [4440977.860566] beta systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> alpha # [4440977.936409] alpha systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> beta # [4440977.869348] beta systemd-journald[91]: Time spent on flushing to /var/log/journal/321548dbfd8643f5824ee6e9f14fd017 is 2.916ms for 7 entries.
container-test-run-data-mesher> alpha # [4440977.938500] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> beta # [4440977.869348] beta systemd-journald[91]: System Journal (/var/log/journal/321548dbfd8643f5824ee6e9f14fd017) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> gamma # [4440978.696616] gamma systemd-networkd[206]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [4440977.890000] beta systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> alpha # [4440977.944340] alpha systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> beta # [4440977.890442] beta systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> alpha # [4440977.945609] alpha systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> beta # [4440977.891662] beta systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [4440978.696698] gamma systemd-networkd[206]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [4440977.891770] beta systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> alpha # [4440977.954978] alpha systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> beta # [4440977.892552] beta systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [4440977.955992] alpha systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [4440977.892595] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [4440978.705596] gamma 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> beta # [4440977.893450] beta systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [4440978.705751] gamma 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> beta # [4440977.894135] beta systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> gamma # [4440978.705892] gamma systemd-networkd[206]: lo: Link UP
container-test-run-data-mesher> beta # [4440977.894166] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [4440977.956142] alpha systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> beta # [4440977.908024] beta systemd-tmpfiles[189]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [4440977.956347] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> beta # [4440977.908197] beta systemd-tmpfiles[189]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [4440977.957447] alpha systemd[1]: Starting Network Management...
container-test-run-data-mesher> beta # [4440977.908311] beta systemd-tmpfiles[189]: fchmod() of /var/log/journal/321548dbfd8643f5824ee6e9f14fd017 failed: Operation not permitted
container-test-run-data-mesher> alpha # [4440978.695666] alpha systemd-networkd[208]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [4440977.908485] beta systemd-tmpfiles[189]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [4440978.695751] alpha systemd-networkd[208]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [4440977.909968] beta systemd[1]: Finished Firewall.
container-test-run-data-mesher> alpha # [4440978.704744] alpha systemd-networkd[208]: /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 # [4440977.910356] beta systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [4440978.704907] alpha systemd-networkd[208]: /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 # [4440977.911126] beta systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> alpha # [4440978.705060] alpha systemd-networkd[208]: lo: Link UP
container-test-run-data-mesher> beta # [4440977.911408] beta systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [4440978.705064] alpha systemd-networkd[208]: lo: Gained carrier
container-test-run-data-mesher> beta # [4440977.912463] beta systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> alpha # [4440978.705247] alpha systemd-networkd[208]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [4440977.913368] beta systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [4440978.705625] alpha systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [4440977.914053] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [4440978.736520] alpha systemd-networkd[208]: eth1: Link UP
container-test-run-data-mesher> beta # [4440977.924800] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [4440978.737009] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [4440977.930331] beta systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [4440978.737038] alpha systemd-networkd[208]: eth1: Gained carrier
container-test-run-data-mesher> beta # [4440977.932618] beta systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> alpha # [4440978.770034] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [4440977.937232] beta systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [4440978.705896] gamma systemd-networkd[206]: lo: Gained carrier
container-test-run-data-mesher> beta # [4440977.941740] beta systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [4440978.706050] gamma systemd-networkd[206]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [4440978.685824] beta systemd-networkd[203]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [4440978.706439] gamma systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [4440978.685916] beta systemd-networkd[203]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [4440978.737008] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [4440978.695013] beta systemd-networkd[203]: /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 # [4440978.737097] gamma systemd-networkd[206]: eth1: Link UP
container-test-run-data-mesher> beta # [4440978.695175] beta systemd-networkd[203]: /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 # [4440978.737380] gamma systemd-networkd[206]: eth1: Gained carrier
container-test-run-data-mesher> beta # [4440978.695333] beta systemd-networkd[203]: lo: Link UP
container-test-run-data-mesher> beta # [4440978.695338] beta systemd-networkd[203]: lo: Gained carrier
container-test-run-data-mesher> beta # [4440978.695525] beta systemd-networkd[203]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [4440978.695912] beta systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [4440978.736453] beta systemd-networkd[203]: eth1: Link UP
container-test-run-data-mesher> beta # [4440978.736956] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [4440978.737022] beta systemd-networkd[203]: eth1: Gained carrier
container-test-run-data-mesher> beta # [4440978.814738] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> beta # [4440978.816191] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> alpha # [4440978.829396] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [4440978.785862] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> gamma # [4440978.820731] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [4440979.256172] gamma systemd-resolved[139]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [4440979.256184] gamma systemd-resolved[139]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [4440979.256186] gamma systemd-resolved[139]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [4440979.256222] gamma systemd-resolved[139]: 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 # [4440979.277831] gamma systemd-resolved[139]: Using system hostname 'gamma'.
container-test-run-data-mesher> gamma # [4440979.279165] gamma systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [4440979.279291] gamma systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [4440979.279412] gamma systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [4440979.279503] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [4440979.279555] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> gamma # [4440979.279594] gamma systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> gamma # [4440979.279817] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> gamma # [4440979.280053] gamma systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> gamma # [4440979.280279] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [4440979.280328] gamma systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> gamma # [4440979.280414] gamma systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [4440979.283337] gamma systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [4440979.284641] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [4440979.286188] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> gamma # [4440979.320582] gamma systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> gamma # [4440979.333493] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [4440979.268649] alpha systemd-resolved[133]: Positive Trust Anchors:
container-test-run-data-mesher> alpha # [4440979.268660] alpha systemd-resolved[133]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> alpha # [4440979.268664] alpha systemd-resolved[133]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> alpha # [4440979.268698] alpha systemd-resolved[133]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
container-test-run-data-mesher> alpha # [4440979.290279] alpha systemd-resolved[133]: Using system hostname 'alpha'.
container-test-run-data-mesher> alpha # [4440979.291594] alpha systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> alpha # [4440979.291716] alpha systemd[1]: Reached target Network.
container-test-run-data-mesher> alpha # [4440979.291832] alpha systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> alpha # [4440979.291920] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [4440979.291972] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [4440979.292034] alpha systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [4440979.292275] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> alpha # [4440979.292479] alpha systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [4440979.292683] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> alpha # [4440979.292732] alpha systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> alpha # [4440979.292816] alpha systemd[1]: Reached target Basic System.
container-test-run-data-mesher> alpha # [4440979.320592] alpha systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> alpha # [4440979.321812] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> alpha # [4440979.322863] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> alpha # [4440979.324354] alpha systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [4440979.339676] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> beta # [4440979.281685] beta systemd-resolved[159]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [4440979.281695] beta systemd-resolved[159]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> beta # [4440979.281699] beta systemd-resolved[159]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> beta # [4440979.281735] beta systemd-resolved[159]: 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 # [4440979.302870] beta systemd-resolved[159]: Using system hostname 'beta'.
container-test-run-data-mesher> beta # [4440979.304792] beta systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> beta # [4440979.304871] beta systemd[1]: Reached target Network.
container-test-run-data-mesher> beta # [4440979.304934] beta systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> beta # [4440979.304975] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [4440979.304994] beta systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> beta # [4440979.305009] beta systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [4440979.305123] beta systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> beta # [4440979.305225] beta systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> beta # [4440979.305322] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> beta # [4440979.305343] beta systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [4440979.305375] beta systemd[1]: Reached target Basic System.
container-test-run-data-mesher> beta # [4440979.320656] beta systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> beta # [4440979.321504] beta systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> beta # [4440979.322337] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> beta # [4440979.323591] beta systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> beta # [4440979.341200] beta systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> beta # [4440979.510927] beta nsncd[216]: Jul 30 09:00:05.563 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> beta # [4440979.510459] beta systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> beta # [4440979.510555] beta systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> beta # [4440979.510651] beta systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> beta # [4440979.512489] beta systemd[1]: Starting User Login Management...
container-test-run-data-mesher> beta # [4440979.513641] beta systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [4440979.509197] gamma nsncd[215]: Jul 30 09:00:05.562 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> gamma # [4440979.509282] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> gamma # [4440979.509376] gamma systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> gamma # [4440979.509489] gamma systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> gamma # [4440979.511560] gamma systemd[1]: Starting User Login Management...
container-test-run-data-mesher> gamma # [4440979.512846] gamma systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [4440979.560330] gamma systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> gamma # [4440979.561971] gamma systemd[1]: Started Console Getty.
container-test-run-data-mesher> gamma # [4440979.562047] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> gamma # [4440979.562084] gamma systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [4440979.734173] gamma dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> gamma # [4440979.734907] gamma dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> gamma # [4440979.734907] gamma dbus-broker-launch[216]: Invalid user-name in /nix/store/6da59hafvrnrphyqfllkn4cla3gaddd5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> gamma # [4440979.735309] gamma systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> gamma # [4440979.742117] gamma dbus-broker-launch[216]: Ready
container-test-run-data-mesher> alpha # [4440979.534285] alpha nsncd[215]: Jul 30 09:00:05.587 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [4440979.534449] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [4440979.534543] alpha systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [4440979.534646] alpha systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> alpha # [4440979.553065] alpha systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [4440979.554405] alpha systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> alpha # [4440979.565254] alpha systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> alpha # [4440979.566701] alpha systemd[1]: Started Console Getty.
container-test-run-data-mesher> alpha # [4440979.566753] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> alpha # [4440979.566774] alpha systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> alpha # [4440979.746813] alpha dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> alpha # [4440979.747731] alpha dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> alpha # [4440979.747731] alpha dbus-broker-launch[216]: Invalid user-name in /nix/store/6da59hafvrnrphyqfllkn4cla3gaddd5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> alpha # [4440979.748199] alpha systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [4440979.754924] alpha dbus-broker-launch[216]: Ready
container-test-run-data-mesher> beta # [4440979.557562] beta systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> beta # [4440979.559090] beta systemd[1]: Started Console Getty.
container-test-run-data-mesher> beta # [4440979.559133] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> beta # [4440979.559149] beta systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> beta # [4440979.736507] beta dbus-broker-launch[217]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [4440979.737198] beta dbus-broker-launch[217]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> beta # [4440979.737198] beta dbus-broker-launch[217]: Invalid user-name in /nix/store/6da59hafvrnrphyqfllkn4cla3gaddd5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [4440979.737588] beta systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> beta # [4440979.745358] beta dbus-broker-launch[217]: Ready
container-test-run-data-mesher> beta # [4440979.876211] beta systemd-networkd[203]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha # [4440979.876167] alpha systemd-networkd[208]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [4440980.259511] beta data-mesher[214]: time=2026-07-30T09:00:06.311Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> alpha # [4440980.259839] alpha data-mesher[213]: time=2026-07-30T09:00:06.312Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> beta # [4440980.260615] beta data-mesher[214]: time=2026-07-30T09:00:06.313Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r: [/dns/alpha.clan/tcp/7946]} {12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4440980.260921] alpha data-mesher[213]: time=2026-07-30T09:00:06.314Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r: [/dns/alpha.clan/tcp/7946]} {12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4440980.260615] beta data-mesher[214]: time=2026-07-30T09:00:06.313Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> beta # [4440980.263329] beta data-mesher[214]: time=2026-07-30T09:00:06.316Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [4440980.263441] beta data-mesher[214]: time=2026-07-30T09:00:06.316Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [4440980.268026] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="libp2p host created" peer_id=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 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 # [4440980.268105] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> beta # [4440980.268105] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> beta # [4440980.268105] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> beta # [4440980.268105] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [4440980.260979] alpha data-mesher[213]: time=2026-07-30T09:00:06.314Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> beta # [4440980.268293] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [4440980.263371] alpha data-mesher[213]: time=2026-07-30T09:00:06.316Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [4440980.268293] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [4440980.263476] alpha data-mesher[213]: time=2026-07-30T09:00:06.316Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [4440980.268293] beta data-mesher[214]: time=2026-07-30T09:00:06.321Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [4440980.267587] alpha data-mesher[213]: time=2026-07-30T09:00:06.320Z level=INFO msg="libp2p host created" peer_id=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r 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> beta # [4440980.273887] beta data-mesher[214]: time=2026-07-30T09:00:06.327Z level=INFO msg="peer connected" peer_id=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [4440980.275137] beta data-mesher[214]: time=2026-07-30T09:00:06.328Z level=INFO msg="peer connected" peer_id=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [4440980.295093] beta data-mesher[214]: time=2026-07-30T09:00:06.348Z level=INFO msg="peer connected" peer_id=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> alpha # [4440980.267634] alpha data-mesher[213]: time=2026-07-30T09:00:06.320Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> alpha # [4440980.267634] alpha data-mesher[213]: time=2026-07-30T09:00:06.320Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> alpha # [4440980.267634] alpha data-mesher[213]: time=2026-07-30T09:00:06.320Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> alpha # [4440980.267703] alpha data-mesher[213]: time=2026-07-30T09:00:06.320Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [4440980.267741] alpha data-mesher[213]: time=2026-07-30T09:00:06.320Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [4440980.267811] alpha data-mesher[213]: time=2026-07-30T09:00:06.320Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [4440980.267838] alpha data-mesher[213]: time=2026-07-30T09:00:06.321Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [4440980.273230] alpha data-mesher[213]: time=2026-07-30T09:00:06.326Z level=INFO msg="peer connected" peer_id=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> alpha # [4440980.275779] alpha data-mesher[213]: time=2026-07-30T09:00:06.328Z level=INFO msg="peer connected" peer_id=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 remote_addr=/ip4/192.168.1.2/tcp/33038
container-test-run-data-mesher> alpha # [4440980.289255] alpha data-mesher[213]: time=2026-07-30T09:00:06.342Z level=INFO msg="peer connected" peer_id=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> gamma # [4440980.276560] gamma data-mesher[213]: time=2026-07-30T09:00:06.329Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> gamma # [4440980.277646] gamma data-mesher[213]: time=2026-07-30T09:00:06.330Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r: [/dns/alpha.clan/tcp/7946]} {12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> gamma # [4440980.277646] gamma data-mesher[213]: time=2026-07-30T09:00:06.330Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> gamma # [4440980.278918] gamma data-mesher[213]: time=2026-07-30T09:00:06.332Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> gamma # [4440980.279032] gamma data-mesher[213]: time=2026-07-30T09:00:06.332Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> gamma # [4440980.283031] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="libp2p host created" peer_id=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 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 # [4440980.283102] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> gamma # [4440980.283102] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> gamma # [4440980.283102] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> gamma # [4440980.283102] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="starting server"
container-test-run-data-mesher> gamma # [4440980.283265] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> gamma # [4440980.283265] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> gamma # [4440980.283265] gamma data-mesher[213]: time=2026-07-30T09:00:06.336Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> gamma # [4440980.287939] gamma data-mesher[213]: time=2026-07-30T09:00:06.341Z level=INFO msg="peer connected" peer_id=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> gamma # [4440980.294433] gamma data-mesher[213]: time=2026-07-30T09:00:06.347Z level=INFO msg="peer connected" peer_id=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> gamma # [4440980.542959] gamma systemd-logind[232]: New seat seat0.
container-test-run-data-mesher> gamma # [4440980.543477] gamma systemd[1]: Started User Login Management.
container-test-run-data-mesher> gamma # [4440980.544923] gamma systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [4440980.562150] gamma systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> gamma # [4440980.562289] gamma systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> gamma # [4440980.772222] gamma systemd-networkd[206]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [4440980.526644] beta systemd-logind[233]: New seat seat0.
container-test-run-data-mesher> beta # [4440980.526865] beta systemd[1]: Started User Login Management.
container-test-run-data-mesher> beta # [4440980.528218] beta systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> beta # [4440980.538396] beta systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> beta # [4440980.538722] beta systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> alpha # [4440980.537691] alpha systemd-logind[232]: New seat seat0.
container-test-run-data-mesher> alpha # [4440980.537887] alpha systemd[1]: Started User Login Management.
container-test-run-data-mesher> alpha # [4440980.538847] alpha systemd[1]: linger-users.service: Failed to spawn executor: No such file or directory
container-test-run-data-mesher> alpha # [4440980.538868] alpha systemd[1]: linger-users.service: Failed to spawn 'start' task: No such file or directory
container-test-run-data-mesher> alpha # [4440980.538895] alpha systemd[1]: linger-users.service: Failed with result 'resources'.
container-test-run-data-mesher> alpha # [4440980.539029] alpha systemd[1]: Failed to start linger-users.service.
container-test-run-data-mesher> alpha: still waiting for container 'alpha' to reach ready state...
container-test-run-data-mesher> alpha: (finished: waiting for unit data-mesher.service, in 13.66 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/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-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/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
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 # [4440990.268215] alpha data-mesher[213]: time=2026-07-30T09:00:16.321Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> alpha # [4440990.268958] alpha data-mesher[213]: time=2026-07-30T09:00:16.321Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> alpha # [4440990.269241] alpha data-mesher[213]: time=2026-07-30T09:00:16.322Z level=INFO msg="received state sync from peer" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4440990.269241] alpha data-mesher[213]: time=2026-07-30T09:00:16.322Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4440990.269904] alpha data-mesher[213]: time=2026-07-30T09:00:16.323Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> alpha # [4440990.269904] alpha data-mesher[213]: time=2026-07-30T09:00:16.323Z level=INFO msg="state exchange complete" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> alpha # [4440990.270018] alpha data-mesher[213]: time=2026-07-30T09:00:16.323Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [4440990.270247] alpha systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [4440990.270778] alpha data-mesher[213]: time=2026-07-30T09:00:16.323Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [4440990.270786] alpha systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> alpha # [4440990.271035] alpha systemd[1]: Startup finished in 12.954s.
container-test-run-data-mesher> alpha # [4440990.284261] alpha data-mesher[213]: time=2026-07-30T09:00:16.337Z level=INFO msg="received state sync from peer" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> alpha # [4440990.284261] alpha data-mesher[213]: time=2026-07-30T09:00:16.337Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> alpha # [4440990.503453] alpha data-mesher[213]: time=2026-07-30T09:00:16.556Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> gamma # [4440990.269640] gamma data-mesher[213]: time=2026-07-30T09:00:16.322Z level=INFO msg="received state sync from peer" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> gamma # [4440990.269640] gamma data-mesher[213]: time=2026-07-30T09:00:16.322Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> gamma # [4440990.283763] gamma data-mesher[213]: time=2026-07-30T09:00:16.336Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [4440990.283763] gamma data-mesher[213]: time=2026-07-30T09:00:16.336Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> gamma # [4440990.284554] gamma data-mesher[213]: time=2026-07-30T09:00:16.337Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> gamma # [4440990.284554] gamma data-mesher[213]: time=2026-07-30T09:00:16.337Z level=INFO msg="state exchange complete" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> gamma # [4440990.284693] gamma data-mesher[213]: time=2026-07-30T09:00:16.337Z level=INFO msg="server started"
container-test-run-data-mesher> gamma # [4440990.284787] gamma data-mesher[213]: time=2026-07-30T09:00:16.337Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> gamma # [4440990.284935] gamma systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> gamma # [4440990.285443] gamma systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> gamma # [4440990.286391] gamma systemd[1]: Startup finished in 12.950s.
container-test-run-data-mesher> beta # [4440990.268494] beta data-mesher[214]: time=2026-07-30T09:00:16.321Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> beta # [4440990.268494] beta data-mesher[214]: time=2026-07-30T09:00:16.321Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> beta # [4440990.269518] beta data-mesher[214]: time=2026-07-30T09:00:16.322Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4440990.269518] beta data-mesher[214]: time=2026-07-30T09:00:16.322Z level=INFO msg="state exchange complete" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> beta # [4440990.269655] beta data-mesher[214]: time=2026-07-30T09:00:16.322Z level=INFO msg="server started"
container-test-run-data-mesher> beta # [4440990.269855] beta data-mesher[214]: time=2026-07-30T09:00:16.322Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> beta # [4440990.269839] beta systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> beta # [4440990.270355] beta systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [4440990.270672] beta systemd[1]: Startup finished in 12.953s.
container-test-run-data-mesher> alpha # [4440995.271433] alpha data-mesher[213]: time=2026-07-30T09:00:21.324Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [4440995.272191] alpha data-mesher[213]: time=2026-07-30T09:00:21.324Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> alpha # [4440995.272648] alpha data-mesher[213]: time=2026-07-30T09:00:21.325Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> alpha # [4440995.272648] alpha data-mesher[213]: time=2026-07-30T09:00:21.325Z level=INFO msg="state exchange complete" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> alpha # [4440995.272822] alpha data-mesher[213]: time=2026-07-30T09:00:21.325Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [4440995.273117] alpha data-mesher[213]: time=2026-07-30T09:00:21.326Z level=INFO msg="received file request" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_file
container-test-run-data-mesher> alpha # [4440995.275582] alpha data-mesher[213]: time=2026-07-30T09:00:21.328Z level=INFO msg="file transfer complete" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_file
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> gamma # [4440995.272392] gamma data-mesher[213]: time=2026-07-30T09:00:21.325Z level=INFO msg="received state sync from peer" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> gamma # [4440995.272392] gamma data-mesher[213]: time=2026-07-30T09:00:21.325Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> gamma # [4440995.273085] gamma data-mesher[213]: time=2026-07-30T09:00:21.325Z level=DEBUG msg="new file detected" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r name=test_file name=test_file
container-test-run-data-mesher> gamma # [4440995.273085] gamma data-mesher[213]: time=2026-07-30T09:00:21.325Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> gamma # [4440995.273085] gamma data-mesher[213]: time=2026-07-30T09:00:21.325Z level=INFO msg="downloading file" name=test_file signed_at="2026-07-30 09:00:16.553 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> gamma # [4440995.273085] gamma data-mesher[213]: time=2026-07-30T09:00:21.326Z level=INFO msg="received state sync from peer" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> gamma # [4440995.273085] gamma data-mesher[213]: time=2026-07-30T09:00:21.326Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> gamma # [4440995.281276] gamma data-mesher[213]: time=2026-07-30T09:00:21.334Z level=INFO msg="download complete" name=test_file signed_at="2026-07-30 09:00:16.553 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r written=true elapsed=8.652122ms
container-test-run-data-mesher> gamma # [4440995.285266] gamma data-mesher[213]: time=2026-07-30T09:00:21.338Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [4440995.285327] gamma data-mesher[213]: time=2026-07-30T09:00:21.338Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> gamma # [4440995.286127] gamma data-mesher[213]: time=2026-07-30T09:00:21.339Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> gamma # [4440995.286127] gamma data-mesher[213]: time=2026-07-30T09:00:21.339Z level=INFO msg="state exchange complete" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> gamma # [4440995.286234] gamma data-mesher[213]: time=2026-07-30T09:00:21.339Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [4440995.286584] gamma data-mesher[213]: time=2026-07-30T09:00:21.339Z level=INFO msg="received file request" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_file
container-test-run-data-mesher> gamma # [4440995.288845] gamma data-mesher[213]: time=2026-07-30T09:00:21.341Z level=INFO msg="file transfer complete" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_file
container-test-run-data-mesher> beta # [4440995.272297] beta data-mesher[214]: time=2026-07-30T09:00:21.325Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [4440995.272297] beta data-mesher[214]: time=2026-07-30T09:00:21.325Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> beta # [4440995.273242] beta data-mesher[214]: time=2026-07-30T09:00:21.326Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> beta # [4440995.273242] beta data-mesher[214]: time=2026-07-30T09:00:21.326Z level=INFO msg="state exchange complete" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> beta # [4440995.273383] beta data-mesher[214]: time=2026-07-30T09:00:21.326Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [4440995.285896] beta data-mesher[214]: time=2026-07-30T09:00:21.339Z level=INFO msg="received state sync from peer" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> beta # [4440995.285896] beta data-mesher[214]: time=2026-07-30T09:00:21.339Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> beta # [4440995.285896] beta data-mesher[214]: time=2026-07-30T09:00:21.339Z level=DEBUG msg="new file detected" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 name=test_file name=test_file
container-test-run-data-mesher> beta # [4440995.286164] beta data-mesher[214]: time=2026-07-30T09:00:21.339Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> beta # [4440995.286164] beta data-mesher[214]: time=2026-07-30T09:00:21.339Z level=INFO msg="downloading file" name=test_file signed_at="2026-07-30 09:00:16.553 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> beta # [4440995.291069] beta data-mesher[214]: time=2026-07-30T09:00:21.344Z level=INFO msg="download complete" name=test_file signed_at="2026-07-30 09:00:16.553 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 written=true elapsed=4.98707ms
container-test-run-data-mesher> beta # [4440995.592393] beta data-mesher[214]: time=2026-07-30T09:00:21.645Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)
container-test-run-data-mesher> alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> alpha # [4441000.273539] alpha data-mesher[213]: time=2026-07-30T09:00:26.326Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [4441000.274254] alpha data-mesher[213]: time=2026-07-30T09:00:26.326Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> alpha # [4441000.274254] alpha data-mesher[213]: time=2026-07-30T09:00:26.327Z level=INFO msg="received state sync from peer" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4441000.274254] alpha data-mesher[213]: time=2026-07-30T09:00:26.327Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4441000.274512] alpha data-mesher[213]: time=2026-07-30T09:00:26.327Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4441000.287131] alpha data-mesher[213]: time=2026-07-30T09:00:26.340Z level=INFO msg="received state sync from peer" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> alpha # [4441000.287131] alpha data-mesher[213]: time=2026-07-30T09:00:26.340Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> alpha # [4441000.328759] alpha data-mesher[213]: time=2026-07-30T09:00:26.381Z level=DEBUG msg="imported tombstone" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 name=test_file written=true
container-test-run-data-mesher> alpha # [4441000.328892] alpha data-mesher[213]: time=2026-07-30T09:00:26.381Z level=DEBUG msg="imported tombstone" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 name=test_file written=false
container-test-run-data-mesher> alpha # [4441000.328892] alpha data-mesher[213]: time=2026-07-30T09:00:26.381Z level=INFO msg="state exchange complete" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> alpha # [4441000.328892] alpha data-mesher[213]: time=2026-07-30T09:00:26.382Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [4441000.286474] gamma data-mesher[213]: time=2026-07-30T09:00:26.339Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [4441000.287162] gamma data-mesher[213]: time=2026-07-30T09:00:26.339Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> gamma # [4441000.287421] gamma data-mesher[213]: time=2026-07-30T09:00:26.340Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> gamma # [4441000.287492] gamma data-mesher[213]: time=2026-07-30T09:00:26.340Z level=INFO msg="state exchange complete" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> gamma # [4441000.287492] gamma data-mesher[213]: time=2026-07-30T09:00:26.340Z 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.00 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 # [4441000.273547] beta data-mesher[214]: time=2026-07-30T09:00:26.326Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [4441000.274244] beta data-mesher[214]: time=2026-07-30T09:00:26.326Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> beta # [4441000.274244] beta data-mesher[214]: time=2026-07-30T09:00:26.327Z level=INFO msg="received state sync from peer" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441000.274244] beta data-mesher[214]: time=2026-07-30T09:00:26.327Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441000.329254] beta data-mesher[214]: time=2026-07-30T09:00:26.382Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441000.329254] beta data-mesher[214]: time=2026-07-30T09:00:26.382Z level=INFO msg="state exchange complete" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> beta # [4441000.329481] beta data-mesher[214]: time=2026-07-30T09:00:26.382Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [4441005.287913] gamma data-mesher[213]: time=2026-07-30T09:00:31.341Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [4441005.288645] gamma data-mesher[213]: time=2026-07-30T09:00:31.341Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> gamma # [4441005.289007] gamma data-mesher[213]: time=2026-07-30T09:00:31.342Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> gamma # [4441005.297038] gamma data-mesher[213]: time=2026-07-30T09:00:31.350Z level=DEBUG msg="imported tombstone" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 name=test_file written=true
container-test-run-data-mesher> gamma # [4441005.297038] gamma data-mesher[213]: time=2026-07-30T09:00:31.350Z level=INFO msg="state exchange complete" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> gamma # [4441005.297167] gamma data-mesher[213]: time=2026-07-30T09:00:31.350Z level=DEBUG msg="push/pull successful" interval=5s
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/1w8v7z9ql9l5jbkly29xfihgyci2vbfd-data-mesher-node-identity_identity.pub
container-test-run-data-mesher> alpha: (finished: must succeed: cat /nix/store/1w8v7z9ql9l5jbkly29xfihgyci2vbfd-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> beta # [4441005.288622] beta data-mesher[214]: time=2026-07-30T09:00:31.341Z level=INFO msg="received state sync from peer" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> beta # [4441005.288622] beta data-mesher[214]: time=2026-07-30T09:00:31.341Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> beta # [4441005.330635] beta data-mesher[214]: time=2026-07-30T09:00:31.383Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [4441005.330721] beta data-mesher[214]: time=2026-07-30T09:00:31.383Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> beta # [4441005.332123] beta data-mesher[214]: time=2026-07-30T09:00:31.385Z level=INFO msg="received state sync from peer" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441005.332190] beta data-mesher[214]: time=2026-07-30T09:00:31.385Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441005.332480] beta data-mesher[214]: time=2026-07-30T09:00:31.385Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441005.332534] beta data-mesher[214]: time=2026-07-30T09:00:31.385Z level=INFO msg="state exchange complete" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> beta # [4441005.332819] beta data-mesher[214]: time=2026-07-30T09:00:31.385Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU' --url http://[::1]:7331 --key /run/secrets/data-mesher-node-identity/identity.key --network-id /nix/store/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-data-mesher-network_network.pub --cert /run/secrets/data-mesher-node-identity/identity.cert
container-test-run-data-mesher> alpha # [4441005.329785] alpha data-mesher[213]: time=2026-07-30T09:00:31.382Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [4441005.330450] alpha data-mesher[213]: time=2026-07-30T09:00:31.382Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> alpha # [4441005.331602] alpha data-mesher[213]: time=2026-07-30T09:00:31.384Z level=INFO msg="received state sync from peer" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4441005.331602] alpha data-mesher[213]: time=2026-07-30T09:00:31.384Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4441005.332502] alpha data-mesher[213]: time=2026-07-30T09:00:31.385Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4441005.332572] alpha data-mesher[213]: time=2026-07-30T09:00:31.385Z level=INFO msg="state exchange complete" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> alpha # [4441005.332624] alpha data-mesher[213]: time=2026-07-30T09:00:31.385Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [4441005.727852] alpha data-mesher[213]: time=2026-07-30T09:00:31.780Z level=INFO msg=http_request uri=/files/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU status=204
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU' --url http://[::1]:7331 --key /run/secrets/data-mesher-node-identity/identity.key --network-id /nix/store/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-data-mesher-network_network.pub --cert /run/secrets/data-mesher-node-identity/identity.cert, in 0.03 seconds)
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> gamma # [4441010.297292] gamma data-mesher[213]: time=2026-07-30T09:00:36.350Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [4441010.297690] alpha data-mesher[213]: time=2026-07-30T09:00:36.350Z level=INFO msg="received state sync from peer" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> gamma # [4441010.297292] gamma data-mesher[213]: time=2026-07-30T09:00:36.350Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> alpha # [4441010.297690] alpha data-mesher[213]: time=2026-07-30T09:00:36.350Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> gamma # [4441010.297926] gamma data-mesher[213]: time=2026-07-30T09:00:36.351Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> alpha # [4441010.298616] alpha data-mesher[213]: time=2026-07-30T09:00:36.351Z level=INFO msg="received file request" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> gamma # [4441010.298142] gamma data-mesher[213]: time=2026-07-30T09:00:36.351Z level=DEBUG msg="new file detected" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r name=test_file name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> alpha # [4441010.301661] alpha data-mesher[213]: time=2026-07-30T09:00:36.354Z level=INFO msg="file transfer complete" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> gamma # [4441010.298168] gamma data-mesher[213]: time=2026-07-30T09:00:36.351Z level=INFO msg="state exchange complete" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r timeout=5s
container-test-run-data-mesher> alpha # [4441010.333615] alpha data-mesher[213]: time=2026-07-30T09:00:36.386Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [4441010.298190] gamma data-mesher[213]: time=2026-07-30T09:00:36.351Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [4441010.298190] gamma data-mesher[213]: time=2026-07-30T09:00:36.351Z level=INFO msg="scheduling file download" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> alpha # [4441010.333692] alpha data-mesher[213]: time=2026-07-30T09:00:36.386Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> gamma # [4441010.298246] gamma data-mesher[213]: time=2026-07-30T09:00:36.351Z level=INFO msg="downloading file" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU signed_at="2026-07-30 09:00:31.776 +0000 UTC" signed_by="fw25N7WePQn0dz1rpllNCu7MWiKTHGRp/a+7Q37c7xU=" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> alpha # [4441010.335240] alpha data-mesher[213]: time=2026-07-30T09:00:36.388Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> alpha # [4441010.335300] alpha data-mesher[213]: time=2026-07-30T09:00:36.388Z level=INFO msg="state exchange complete" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 timeout=5s
container-test-run-data-mesher> alpha # [4441010.335300] alpha data-mesher[213]: time=2026-07-30T09:00:36.388Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [4441010.335663] alpha data-mesher[213]: time=2026-07-30T09:00:36.388Z level=INFO msg="received file request" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> alpha # [4441010.337907] alpha data-mesher[213]: time=2026-07-30T09:00:36.391Z level=INFO msg="file transfer complete" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2 network="rtiAEXRFGVQjs5wbaChM2MDxgvE3k7AvGzaqJNAuk5w=" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> gamma # [4441010.302648] gamma data-mesher[213]: time=2026-07-30T09:00:36.355Z level=INFO msg="download complete" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU signed_at="2026-07-30 09:00:31.776 +0000 UTC" signed_by="fw25N7WePQn0dz1rpllNCu7MWiKTHGRp/a+7Q37c7xU=" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r written=true elapsed=4.424582ms
container-test-run-data-mesher> gamma # [4441010.333916] gamma data-mesher[213]: time=2026-07-30T09:00:36.387Z level=INFO msg="received state sync from peer" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> gamma # [4441010.333947] gamma data-mesher[213]: time=2026-07-30T09:00:36.387Z level=INFO msg="merging remote state" peer=12D3KooWAuufKwGqYVn54drT5Abbaf1UcSQnUpyJhRzF5W6zNYs2
container-test-run-data-mesher> beta # [4441010.333350] beta data-mesher[214]: time=2026-07-30T09:00:36.386Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [4441010.333350] beta data-mesher[214]: time=2026-07-30T09:00:36.386Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> beta # [4441010.334286] beta data-mesher[214]: time=2026-07-30T09:00:36.387Z level=INFO msg="received state sync from peer" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441010.334333] beta data-mesher[214]: time=2026-07-30T09:00:36.387Z level=INFO msg="merging remote state" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441010.334386] beta data-mesher[214]: time=2026-07-30T09:00:36.387Z level=INFO msg="merging remote state" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6
container-test-run-data-mesher> beta # [4441010.334933] beta data-mesher[214]: time=2026-07-30T09:00:36.388Z level=DEBUG msg="new file detected" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r name=test_file name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> beta # [4441010.335012] beta data-mesher[214]: time=2026-07-30T09:00:36.388Z level=DEBUG msg="new file detected" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 name=test_file name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> beta # [4441010.335046] beta data-mesher[214]: time=2026-07-30T09:00:36.388Z level=INFO msg="state exchange complete" peer=12D3KooWK4BioecFiq4L5AEbnTpymU8q6zJUyKDKmLSEp7KZ1eS6 timeout=5s
container-test-run-data-mesher> beta # [4441010.335082] beta data-mesher[214]: time=2026-07-30T09:00:36.388Z level=INFO msg="scheduling file download" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> beta # [4441010.335112] beta data-mesher[214]: time=2026-07-30T09:00:36.388Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [4441010.335177] beta data-mesher[214]: time=2026-07-30T09:00:36.388Z level=INFO msg="downloading file" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU signed_at="2026-07-30 09:00:31.776 +0000 UTC" signed_by="fw25N7WePQn0dz1rpllNCu7MWiKTHGRp/a+7Q37c7xU=" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r
container-test-run-data-mesher> beta # [4441010.339440] beta data-mesher[214]: time=2026-07-30T09:00:36.392Z level=INFO msg="download complete" name=test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU signed_at="2026-07-30 09:00:31.776 +0000 UTC" signed_by="fw25N7WePQn0dz1rpllNCu7MWiKTHGRp/a+7Q37c7xU=" peer=12D3KooWJNL5oN7GXNfhGUpWybaKEJoVicXTS56iPbtgArx3rt9r written=true elapsed=4.292861ms
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU, in 5.04 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU, 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/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU' --url http://[::1]:7331 --key /run/secrets/data-mesher-node-identity/identity.key --network-id /nix/store/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-data-mesher-network_network.pub
container-test-run-data-mesher> Error: failed to update file: 403 Forbidden, signer fw25N7WePQn0dz1rpllNCu7MWiKTHGRp/a+7Q37c7xU= is not authorized for this file test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU
container-test-run-data-mesher> alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/fw25N7WePQn0dz1rpllNCu7MWiKTHGRp_a-7Q37c7xU' --url http://[::1]:7331 --key /run/secrets/data-mesher-node-identity/identity.key --network-id /nix/store/2bsi2w6r2l49vjw2718fzbkpscp6l8m5-data-mesher-network_network.pub, in 0.03 seconds)
container-test-run-data-mesher> (finished: run the VM test script, in 34.05 seconds)
container-test-run-data-mesher> test script finished in 34.10s
container-test-run-data-mesher> cleanup
container-test-run-data-mesher> kill NspawnMachine (pid 52)
container-test-run-data-mesher> kill NspawnMachine (pid 54)
container-test-run-data-mesher> Container alpha terminated by signal KILL.
container-test-run-data-mesher> kill NspawnMachine (pid 57)
container-test-run-data-mesher> Container beta terminated by signal KILL.
container-test-run-data-mesher> Container gamma terminated by signal KILL.
container-test-run-data-mesher> (finished: cleanup, in 0.49 seconds)

post-build step Upload to niks3: ok
time=2026-07-30T09:00:37.911Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-07-30T09:00:38.182Z level=INFO msg="Uploading 1 narinfos"
time=2026-07-30T09:00:38.635Z level=INFO msg="Upload complete. (776ms)"