these 15 derivations will be built:
  /nix/store/n9m525i9xcsfpwi9ihkh6mj0dyrdrggh-etc.drv
  /nix/store/0q7yd3zy3f4xf7pv8inq5cc7v1vps1ry-activate.drv
  /nix/store/0x85mpah4ny0vb16yizyagz9bznv9qiy-etc.drv
  /nix/store/8f2946icwam9q4kighddllkj5mapyqzj-nixos-system-beta-test.drv
  /nix/store/8d0v3y1f5xqisl9m314c215ybm3vrfnz-run-beta-nspawn.drv
  /nix/store/vgjz8hd0gs7ps44c0ra22davcjnfn8bn-etc.drv
  /nix/store/b410cpmk7g4iiq40xqqywig78vq1fcvg-activate.drv
  /nix/store/p7yxc5kw66sxbff1fyj4dgs35i9jl8gb-nixos-system-gamma-test.drv
  /nix/store/8dl6gmvlbpf9ibgmqddf1s0cqp1f7jsa-run-gamma-nspawn.drv
  /nix/store/gjqs6jxidhd4h6an2mha8ax4j40bf6if-activate.drv
  /nix/store/bryhmmins5mrl0k4p6x5ab3izb87fy6l-nixos-system-alpha-test.drv
  /nix/store/xd6i5i8fzfjfjifa3z9nxv4pnra803zy-run-alpha-nspawn.drv
  /nix/store/5dkkhh24s48157fdlsb7k7zx208m994h-driverConfiguration.json.drv
  /nix/store/6fhss5f31fjnzqlysmwhlwmzhaxkvdqz-nixos-test-driver-data-mesher.drv
  /nix/store/k2r55ix8xxgc6rl0xjynxakmzfw9sj8l-container-test-run-data-mesher.drv
this path will be fetched (0.1 KiB download, 0.2 KiB unpacked):
  /nix/store/wr26g55lhl9vrzb4gyw6b4hjms5is774-55-nixos-aslr-entropy.conf
building '/nix/store/0x85mpah4ny0vb16yizyagz9bznv9qiy-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/n9m525i9xcsfpwi9ihkh6mj0dyrdrggh-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vgjz8hd0gs7ps44c0ra22davcjnfn8bn-etc.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/0x85mpah4ny0vb16yizyagz9bznv9qiy-etc.drv'
building '/nix/store/n9m525i9xcsfpwi9ihkh6mj0dyrdrggh-etc.drv'
building '/nix/store/gjqs6jxidhd4h6an2mha8ax4j40bf6if-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/0q7yd3zy3f4xf7pv8inq5cc7v1vps1ry-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/vgjz8hd0gs7ps44c0ra22davcjnfn8bn-etc.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/gjqs6jxidhd4h6an2mha8ax4j40bf6if-activate.drv'
activate> structuredAttrs is enabled
building '/nix/store/b410cpmk7g4iiq40xqqywig78vq1fcvg-activate.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/0q7yd3zy3f4xf7pv8inq5cc7v1vps1ry-activate.drv'
activate> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/bryhmmins5mrl0k4p6x5ab3izb87fy6l-nixos-system-alpha-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/8f2946icwam9q4kighddllkj5mapyqzj-nixos-system-beta-test.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/b410cpmk7g4iiq40xqqywig78vq1fcvg-activate.drv'
activate> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/bryhmmins5mrl0k4p6x5ab3izb87fy6l-nixos-system-alpha-test.drv'
nixos-system-alpha-test> structuredAttrs is enabled
building '/nix/store/xd6i5i8fzfjfjifa3z9nxv4pnra803zy-run-alpha-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/8f2946icwam9q4kighddllkj5mapyqzj-nixos-system-beta-test.drv'
nixos-system-beta-test> structuredAttrs is enabled
building '/nix/store/8d0v3y1f5xqisl9m314c215ybm3vrfnz-run-beta-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
building '/nix/store/p7yxc5kw66sxbff1fyj4dgs35i9jl8gb-nixos-system-gamma-test.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/p7yxc5kw66sxbff1fyj4dgs35i9jl8gb-nixos-system-gamma-test.drv'
nixos-system-gamma-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/8d0v3y1f5xqisl9m314c215ybm3vrfnz-run-beta-nspawn.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/xd6i5i8fzfjfjifa3z9nxv4pnra803zy-run-alpha-nspawn.drv'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/8dl6gmvlbpf9ibgmqddf1s0cqp1f7jsa-run-gamma-nspawn.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/8dl6gmvlbpf9ibgmqddf1s0cqp1f7jsa-run-gamma-nspawn.drv'
building '/nix/store/5dkkhh24s48157fdlsb7k7zx208m994h-driverConfiguration.json.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/5dkkhh24s48157fdlsb7k7zx208m994h-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/6fhss5f31fjnzqlysmwhlwmzhaxkvdqz-nixos-test-driver-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/6fhss5f31fjnzqlysmwhlwmzhaxkvdqz-nixos-test-driver-data-mesher.drv'
nixos-test-driver-data-mesher> Running type check (enable/disable: config.skipTypeCheck)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
nixos-test-driver-data-mesher> All checks passed!
nixos-test-driver-data-mesher> Linting test script (enable/disable: config.skipLint)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
nixos-test-driver-data-mesher> All checks passed!
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/k2r55ix8xxgc6rl0xjynxakmzfw9sj8l-container-test-run-data-mesher.drv' on 'ssh-ng://builder@build01.clan.lol'
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy
building '/nix/store/k2r55ix8xxgc6rl0xjynxakmzfw9sj8l-container-test-run-data-mesher.drv'
container-test-run-data-mesher> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-data-mesher> start all VLans
container-test-run-data-mesher> (finished: start all VLans, in 0.00 seconds)
container-test-run-data-mesher> 
container-test-run-data-mesher> Test will time out and terminate in 3600.0 seconds
container-test-run-data-mesher> run the VM test script
container-test-run-data-mesher> additionally exposed symbols:
container-test-run-data-mesher>     alpha, beta, gamma,
container-test-run-data-mesher>     vlan1,
container-test-run-data-mesher>     start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
container-test-run-data-mesher> start all VMs
container-test-run-data-mesher> beta: systemd-nspawn running (pid 53)
container-test-run-data-mesher> alpha: systemd-nspawn running (pid 54)
container-test-run-data-mesher> gamma: systemd-nspawn running (pid 55)
container-test-run-data-mesher> beta: Waiting for journal at /build/vm-state-beta/var/log/journal...
container-test-run-data-mesher> alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...
container-test-run-data-mesher> gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...
container-test-run-data-mesher> (finished: start all VMs, in 0.00 seconds)
container-test-run-data-mesher> alpha: waiting for unit data-mesher.service
container-test-run-data-mesher> nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(gamma): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(beta): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(alpha): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> ░ Spawning container beta on /build/vm-state-beta.
container-test-run-data-mesher> ░ Spawning container alpha on /build/vm-state-alpha.
container-test-run-data-mesher> ░ Spawning container gamma on /build/vm-state-gamma.
container-test-run-data-mesher> alpha # No journal files were found.
container-test-run-data-mesher> gamma # No journal files were found.
container-test-run-data-mesher> alpha # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> gamma # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> beta # No journal files were found.
container-test-run-data-mesher> beta # No journal boot entry found for the specified boot (+0).
container-test-run-data-mesher> gamma # [1582724.744461] gamma systemd-journald[90]: Journal started
container-test-run-data-mesher> alpha # [1582724.707426] alpha systemd-journald[90]: Journal started
container-test-run-data-mesher> gamma # [1582724.744513] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/f6d83f30e8a5446297c101c450c3a2b8) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> beta # [1582724.707509] beta systemd-journald[90]: Journal started
container-test-run-data-mesher> gamma # [1582724.759139] gamma systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> beta # [1582724.707558] beta systemd-journald[90]: Runtime Journal (/run/log/journal/a19dc5def6724cc99963507d2292f8e9) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> gamma # [1582724.779073] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> beta # [1582724.727226] beta systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> gamma # [1582724.802273] gamma systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [1582724.741990] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> gamma # [1582724.803102] gamma systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [1582724.756737] beta systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> gamma # [1582724.803835] gamma systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [1582724.757569] beta systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> gamma # [1582724.813752] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/f6d83f30e8a5446297c101c450c3a2b8 is 1.690ms for 7 entries.
container-test-run-data-mesher> beta # [1582724.758238] beta systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> gamma # [1582724.813752] gamma systemd-journald[90]: System Journal (/var/log/journal/f6d83f30e8a5446297c101c450c3a2b8) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [1582724.766760] beta systemd-journald[90]: Time spent on flushing to /var/log/journal/a19dc5def6724cc99963507d2292f8e9 is 1.675ms for 7 entries.
container-test-run-data-mesher> gamma # [1582724.836791] gamma systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> beta # [1582724.766760] beta systemd-journald[90]: System Journal (/var/log/journal/a19dc5def6724cc99963507d2292f8e9) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> gamma # [1582724.837237] gamma systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> beta # [1582724.797767] beta systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> gamma # [1582724.838568] gamma systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [1582724.798329] beta systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [1582724.838679] gamma systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> beta # [1582724.799678] beta systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [1582724.839510] gamma systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [1582724.707483] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/1cff835689464dfa8f789b99450bcc9a) is 8M, max 2.5G, 2.4G free.
container-test-run-data-mesher> gamma # [1582724.839560] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [1582724.729549] alpha systemd[1]: Finished Apply Kernel Variables.
container-test-run-data-mesher> gamma # [1582724.840716] gamma systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> alpha # [1582724.742882] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> gamma # [1582724.841498] gamma systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [1582724.757146] alpha systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [1582724.799788] beta systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> alpha # [1582724.757933] alpha systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [1582724.800647] beta systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [1582724.758569] alpha systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [1582724.800701] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [1582724.766250] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/1cff835689464dfa8f789b99450bcc9a is 1.722ms for 7 entries.
container-test-run-data-mesher> beta # [1582724.801490] beta systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> alpha # [1582724.766250] alpha systemd-journald[90]: System Journal (/var/log/journal/1cff835689464dfa8f789b99450bcc9a) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [1582724.802227] beta systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [1582724.797755] alpha systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> beta # [1582724.802262] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [1582724.798309] alpha systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> beta # [1582724.817936] beta systemd-tmpfiles[165]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [1582724.799601] alpha systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [1582724.818109] beta systemd-tmpfiles[165]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [1582724.799706] alpha systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> beta # [1582724.818223] beta systemd-tmpfiles[165]: fchmod() of /var/log/journal/a19dc5def6724cc99963507d2292f8e9 failed: Operation not permitted
container-test-run-data-mesher> alpha # [1582724.800549] alpha systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> beta # [1582724.818395] beta systemd-tmpfiles[165]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [1582724.800597] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [1582724.825189] beta systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [1582724.801479] alpha systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> beta # [1582724.826408] beta systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> alpha # [1582724.802244] alpha systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> beta # [1582724.827133] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [1582724.802275] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> beta # [1582724.838837] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [1582724.819748] alpha systemd-tmpfiles[165]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [1582724.845780] beta systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> beta # [1582724.846933] beta systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [1582724.841541] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [1582724.819928] alpha systemd-tmpfiles[165]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [1582724.857013] beta systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> alpha # [1582724.820542] alpha systemd-tmpfiles[165]: fchmod() of /var/log/journal/1cff835689464dfa8f789b99450bcc9a failed: Operation not permitted
container-test-run-data-mesher> beta # [1582724.862213] beta systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [1582724.862373] beta systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [1582724.856315] gamma systemd-tmpfiles[167]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [1582724.820825] alpha systemd-tmpfiles[165]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [1582724.862606] beta systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [1582724.856483] gamma systemd-tmpfiles[167]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [1582724.863924] beta systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [1582724.856593] gamma systemd-tmpfiles[167]: fchmod() of /var/log/journal/f6d83f30e8a5446297c101c450c3a2b8 failed: Operation not permitted
container-test-run-data-mesher> beta # [1582724.958208] beta systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [1582724.856771] gamma systemd-tmpfiles[167]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [1582725.697925] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [1582724.858200] gamma systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [1582724.822331] alpha systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [1582724.859293] gamma systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> alpha # [1582724.823534] alpha systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [1582724.860267] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [1582724.824443] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [1582724.872556] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [1582724.836377] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> gamma # [1582724.879040] gamma systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [1582724.843619] alpha systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> gamma # [1582724.880836] gamma systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> alpha # [1582724.844782] alpha systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [1582724.891348] gamma systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> alpha # [1582724.856085] alpha systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [1582724.898427] gamma systemd[1]: Finished Firewall.
container-test-run-data-mesher> alpha # [1582724.863802] alpha systemd[1]: Finished Firewall.
container-test-run-data-mesher> gamma # [1582724.898585] gamma systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> alpha # [1582724.863970] alpha systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [1582724.898816] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [1582724.864214] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [1582724.899839] gamma systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [1582724.865229] alpha systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [1582724.957977] gamma systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> alpha # [1582724.958253] alpha systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> alpha # [1582725.742925] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [1582725.740120] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> beta # [1582726.535536] beta systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [1582726.535630] beta systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [1582726.547759] beta systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> beta # [1582726.547936] beta systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> beta # [1582726.548137] beta systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> beta # [1582726.548142] beta systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> beta # [1582726.548501] beta systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [1582726.549019] beta systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [1582726.549031] beta systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> beta # [1582726.549265] beta systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> beta # [1582726.552094] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [1582726.602447] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> alpha # [1582726.523008] alpha systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [1582726.523103] alpha systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [1582726.532868] alpha systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> alpha # [1582726.533044] alpha systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> alpha # [1582726.533211] alpha systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> alpha # [1582726.533217] alpha systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> alpha # [1582726.533421] alpha systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [1582726.533823] alpha systemd[1]: Started Network Management.
container-test-run-data-mesher> alpha # [1582726.544385] alpha systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> alpha # [1582726.544662] alpha systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> alpha # [1582726.545880] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> alpha # [1582726.582122] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> gamma # [1582726.701528] gamma systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [1582726.701622] gamma systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [1582726.714302] gamma systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> gamma # [1582726.714476] gamma systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.
container-test-run-data-mesher> gamma # [1582726.714643] gamma systemd-networkd[207]: lo: Link UP
container-test-run-data-mesher> gamma # [1582726.714646] gamma systemd-networkd[207]: lo: Gained carrier
container-test-run-data-mesher> gamma # [1582726.714825] gamma systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> gamma # [1582726.715271] gamma systemd[1]: Started Network Management.
container-test-run-data-mesher> gamma # [1582726.715345] gamma systemd-networkd[207]: eth1: Link UP
container-test-run-data-mesher> gamma # [1582726.715672] gamma systemd-networkd[207]: eth1: Gained carrier
container-test-run-data-mesher> gamma # [1582726.716756] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> gamma # [1582726.766486] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [1582727.684171] beta systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [1582727.907374] beta systemd-resolved[134]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [1582727.907386] beta systemd-resolved[134]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> beta # [1582727.907392] beta systemd-resolved[134]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> beta # [1582727.907439] beta systemd-resolved[134]: 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 # [1582727.934981] beta systemd-resolved[134]: Using system hostname 'beta'.
container-test-run-data-mesher> alpha # [1582727.806429] alpha systemd-resolved[133]: Positive Trust Anchors:
container-test-run-data-mesher> alpha # [1582727.806442] alpha systemd-resolved[133]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> alpha # [1582727.806445] alpha systemd-resolved[133]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> alpha # [1582727.806480] 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 # [1582727.829761] alpha systemd-resolved[133]: Using system hostname 'alpha'.
container-test-run-data-mesher> alpha # [1582727.831229] alpha systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> alpha # [1582727.831345] alpha systemd[1]: Reached target Network.
container-test-run-data-mesher> alpha # [1582727.831413] alpha systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> alpha # [1582727.831463] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [1582727.831494] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [1582727.831508] alpha systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [1582727.831646] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> alpha # [1582727.831765] alpha systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [1582727.831879] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> alpha # [1582727.831902] alpha systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> alpha # [1582727.831945] alpha systemd[1]: Reached target Basic System.
container-test-run-data-mesher> alpha # [1582727.833310] alpha systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> alpha # [1582727.834151] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> alpha # [1582727.835361] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> alpha # [1582727.836827] alpha systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [1582727.856374] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [1582727.872210] alpha systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [1582727.936684] beta systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [1582727.941316] gamma systemd-resolved[140]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [1582727.936847] beta systemd[1]: Reached target Network.
container-test-run-data-mesher> beta # [1582727.936969] beta systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [1582727.941329] gamma systemd-resolved[140]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [1582727.941333] gamma systemd-resolved[140]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [1582727.941367] gamma systemd-resolved[140]: 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 # [1582727.964031] gamma systemd-resolved[140]: Using system hostname 'gamma'.
container-test-run-data-mesher> gamma # [1582727.965500] gamma systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [1582727.965588] gamma systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [1582727.965657] gamma systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [1582727.965710] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [1582727.965738] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> gamma # [1582727.965756] gamma systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [1582727.937084] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [1582727.937144] beta systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> beta # [1582727.937183] beta systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [1582727.937430] beta systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> beta # [1582727.937684] beta systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> beta # [1582727.937913] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> beta # [1582727.937963] beta systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [1582727.938047] beta systemd[1]: Reached target Basic System.
container-test-run-data-mesher> beta # [1582727.941662] beta systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> beta # [1582727.943268] beta systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [1582727.965883] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> gamma # [1582727.966011] gamma systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> gamma # [1582727.966117] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [1582727.966138] gamma systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [1582727.944846] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> beta # [1582727.992530] beta systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> beta # [1582728.009358] beta systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> gamma # [1582727.966175] gamma systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [1582727.992579] gamma systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [1582727.993471] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [1582727.994301] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> gamma # [1582727.995579] gamma systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> gamma # [1582728.011653] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [1582728.337022] alpha nsncd[215]: Sep 22 15:05:48.322 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [1582728.336916] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [1582728.336999] alpha systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [1582728.337066] alpha systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> alpha # [1582728.338370] alpha systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [1582728.339235] alpha systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> alpha # [1582728.349717] alpha systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> alpha # [1582728.351013] alpha systemd[1]: Started Console Getty.
container-test-run-data-mesher> alpha # [1582728.351065] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> alpha # [1582728.351088] alpha systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> beta # [1582728.459127] beta systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> beta # [1582728.459195] beta systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> beta # [1582728.459255] beta systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> beta # [1582728.460395] beta systemd[1]: Starting User Login Management...
container-test-run-data-mesher> beta # [1582728.461280] beta systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> beta # [1582728.464140] beta nsncd[215]: Sep 22 15:05:48.444 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> beta # [1582728.511449] beta systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> beta # [1582728.512494] beta systemd[1]: Started Console Getty.
container-test-run-data-mesher> beta # [1582728.512532] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> beta # [1582728.512551] beta systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [1582728.448174] gamma systemd-networkd[207]: eth1: Gained IPv6LL
container-test-run-data-mesher> gamma # [1582728.544943] gamma nsncd[215]: Sep 22 15:05:48.530 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> gamma # [1582728.545007] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> gamma # [1582728.545084] gamma systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> gamma # [1582728.545156] gamma systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> gamma # [1582728.546436] gamma systemd[1]: Starting User Login Management...
container-test-run-data-mesher> gamma # [1582728.547361] gamma systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [1582728.557545] gamma systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> gamma # [1582728.558486] gamma systemd[1]: Started Console Getty.
container-test-run-data-mesher> gamma # [1582728.558521] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> gamma # [1582728.558538] gamma systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [1582729.094393] gamma dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [1582729.138551] beta dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [1582729.139411] beta dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> beta # [1582729.139411] beta dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> alpha # [1582729.026075] alpha dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> gamma # [1582729.095210] gamma dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> alpha # [1582729.026996] alpha dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> gamma # [1582729.095210] gamma dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [1582729.139867] beta systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [1582729.026996] alpha dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> gamma # [1582729.095622] gamma systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [1582729.031361] alpha systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [1582729.038922] alpha dbus-broker-launch[216]: Ready
container-test-run-data-mesher> gamma # [1582729.102620] gamma dbus-broker-launch[216]: Ready
container-test-run-data-mesher> beta # [1582729.148025] beta dbus-broker-launch[216]: Ready
container-test-run-data-mesher> gamma # [1582731.577093] gamma data-mesher[213]: time=2026-09-22T15:05:51.562Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> gamma # [1582731.578325] gamma data-mesher[213]: time=2026-09-22T15:05:51.564Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> gamma # [1582731.578325] gamma data-mesher[213]: time=2026-09-22T15:05:51.564Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> gamma # [1582731.637675] gamma data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> gamma # [1582731.637845] gamma data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> gamma # [1582731.642198] gamma data-mesher[213]: time=2026-09-22T15:05:51.627Z level=INFO msg="libp2p host created" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.3/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::3/tcp/7946]"
container-test-run-data-mesher> gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="starting server"
container-test-run-data-mesher> gamma # [1582731.642491] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> gamma # [1582731.642491] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> gamma # [1582731.642491] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> gamma # [1582731.646495] gamma data-mesher[213]: time=2026-09-22T15:05:51.632Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip6/2001:db8:1::1/tcp/7946
container-test-run-data-mesher> gamma # [1582731.652363] gamma data-mesher[213]: time=2026-09-22T15:05:51.638Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/7946
container-test-run-data-mesher> gamma # [1582731.656421] gamma data-mesher[213]: time=2026-09-22T15:05:51.642Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> gamma # [1582731.656421] gamma data-mesher[213]: time=2026-09-22T15:05:51.642Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/49848
container-test-run-data-mesher> alpha # [1582731.592399] alpha data-mesher[213]: time=2026-09-22T15:05:51.578Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> alpha # [1582731.593427] alpha data-mesher[213]: time=2026-09-22T15:05:51.579Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [1582731.593507] alpha data-mesher[213]: time=2026-09-22T15:05:51.579Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> alpha # [1582731.637931] alpha data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> alpha # [1582731.638047] alpha data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> alpha # [1582731.642230] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="libp2p host created" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.1/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::1/tcp/7946]"
container-test-run-data-mesher> alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [1582731.642373] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [1582731.642466] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [1582731.642492] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [1582731.647306] alpha data-mesher[213]: time=2026-09-22T15:05:51.633Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip6/2001:db8:1::3/tcp/7946
container-test-run-data-mesher> alpha # [1582731.647724] alpha data-mesher[213]: time=2026-09-22T15:05:51.633Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/7946
container-test-run-data-mesher> alpha # [1582731.648864] alpha data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/57818
container-test-run-data-mesher> beta # [1582731.610816] beta data-mesher[213]: time=2026-09-22T15:05:51.596Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> beta # [1582731.612374] beta data-mesher[213]: time=2026-09-22T15:05:51.598Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [1582731.612425] beta data-mesher[213]: time=2026-09-22T15:05:51.598Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> beta # [1582731.637943] beta data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [1582731.638092] beta data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [1582731.642198] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="libp2p host created" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946]"
container-test-run-data-mesher> beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="starting server"
container-test-run-data-mesher> beta # [1582731.642348] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> beta # [1582731.642460] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> beta # [1582731.642486] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> beta # [1582731.648158] beta data-mesher[213]: time=2026-09-22T15:05:51.633Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip6/2001:db8:1::1/tcp/7946
container-test-run-data-mesher> beta # [1582731.648823] beta data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [1582731.649047] beta data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer disconnected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [1582731.649112] beta data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip6/2001:db8:1::1/tcp/7946
container-test-run-data-mesher> beta # [1582731.653001] beta data-mesher[213]: time=2026-09-22T15:05:51.638Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip6/2001:db8:1::3/tcp/7946
container-test-run-data-mesher> beta # [1582731.655687] beta data-mesher[213]: time=2026-09-22T15:05:51.641Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> beta # [1582731.655753] beta data-mesher[213]: time=2026-09-22T15:05:51.641Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip6/2001:db8:1::3/tcp/7946
container-test-run-data-mesher> beta # [1582731.950240] beta systemd-logind[232]: New seat seat0.
container-test-run-data-mesher> beta # [1582731.950489] beta systemd[1]: Started User Login Management.
container-test-run-data-mesher> beta # [1582731.952941] beta systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> beta # [1582732.004301] beta systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> beta # [1582732.004479] beta systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> alpha # [1582731.880830] alpha systemd-logind[232]: New seat seat0.
container-test-run-data-mesher> alpha # [1582731.881070] alpha systemd[1]: Started User Login Management.
container-test-run-data-mesher> alpha # [1582731.882727] alpha systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> alpha # [1582731.895331] alpha systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> alpha # [1582731.895577] alpha systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> gamma # [1582732.008145] gamma systemd-logind[232]: New seat seat0.
container-test-run-data-mesher> gamma # [1582732.009806] gamma systemd[1]: Started User Login Management.
container-test-run-data-mesher> gamma # [1582732.011745] gamma systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [1582732.027798] gamma systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> gamma # [1582732.027983] gamma systemd[1]: Finished 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 18.16 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.01 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.01 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'hello world' > /tmp/test_file
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> alpha # [1582741.642638] alpha data-mesher[213]: time=2026-09-22T15:06:01.628Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> alpha # [1582741.642638] alpha data-mesher[213]: time=2026-09-22T15:06:01.628Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [1582741.643592] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582741.643592] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [1582741.643730] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [1582741.643730] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [1582741.643876] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [1582741.643876] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [1582741.643916] alpha systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [1582741.644211] alpha systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> alpha # [1582741.648273] alpha systemd[1]: Startup finished in 17.351s.
container-test-run-data-mesher> alpha # [1582741.913955] alpha data-mesher[213]: time=2026-09-22T15:06:01.899Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)
container-test-run-data-mesher> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-data-mesher>     File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-data-mesher>     File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma # [1582741.643023] gamma data-mesher[213]: time=2026-09-22T15:06:01.628Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [1582741.643023] gamma data-mesher[213]: time=2026-09-22T15:06:01.628Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [1582741.643862] gamma data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [1582741.643862] gamma data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [1582741.644318] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [1582741.644318] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [1582741.644414] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="server started"
container-test-run-data-mesher> gamma # [1582741.644447] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> gamma # [1582741.644978] gamma systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> gamma # [1582741.645499] gamma systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> gamma # [1582741.648285] gamma systemd[1]: Startup finished in 17.306s.
container-test-run-data-mesher> beta # [1582741.642473] beta data-mesher[213]: time=2026-09-22T15:06:01.628Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> beta # [1582741.642886] beta data-mesher[213]: time=2026-09-22T15:06:01.628Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> beta # [1582741.643254] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582741.643291] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582741.644090] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [1582741.644090] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> beta # [1582741.644143] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="server started"
container-test-run-data-mesher> beta # [1582741.644224] beta data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> beta # [1582741.644324] beta systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> beta # [1582741.644566] beta systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [1582741.648291] beta systemd[1]: Startup finished in 17.346s.
container-test-run-data-mesher> gamma # [1582746.645185] gamma data-mesher[213]: time=2026-09-22T15:06:06.630Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [1582746.645725] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> beta # [1582746.645515] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> alpha # [1582746.644138] alpha data-mesher[213]: time=2026-09-22T15:06:06.629Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [1582746.644745] alpha data-mesher[213]: time=2026-09-22T15:06:06.629Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [1582746.645703] alpha data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582746.645703] alpha data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [1582746.645796] alpha data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [1582746.646291] alpha data-mesher[213]: time=2026-09-22T15:06:06.632Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> gamma # [1582746.646014] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582746.648114] alpha data-mesher[213]: time=2026-09-22T15:06:06.633Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> beta # [1582746.645515] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file
container-test-run-data-mesher> beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [1582746.646348] beta data-mesher[213]: time=2026-09-22T15:06:06.632Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [1582746.646348] beta data-mesher[213]: time=2026-09-22T15:06:06.632Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> beta # [1582746.646348] beta data-mesher[213]: time=2026-09-22T15:06:06.632Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [1582746.652594] beta data-mesher[213]: time=2026-09-22T15:06:06.638Z level=INFO msg="download complete" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=6.952056ms
container-test-run-data-mesher> gamma # [1582746.646014] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [1582746.646112] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [1582746.646112] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [1582746.646112] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_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 # [1582751.646469] gamma data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [1582751.646469] gamma data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [1582751.647237] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [1582751.647282] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="new file detected" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file name=test_file
container-test-run-data-mesher> gamma # [1582751.647282] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> beta # [1582751.646410] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [1582751.647282] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [1582751.646798] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [1582751.647464] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> gamma # [1582751.647538] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582751.646555] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [1582751.646966] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [1582751.662473] gamma data-mesher[213]: time=2026-09-22T15:06:11.648Z level=INFO msg="download complete" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC written=true elapsed=14.960165ms
container-test-run-data-mesher> alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> beta # [1582751.646966] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [1582751.647079] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582751.647079] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582751.647183] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582751.647210] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [1582751.647233] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [1582751.647919] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> beta # [1582751.649063] beta data-mesher[213]: time=2026-09-22T15:06:11.634Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)
container-test-run-data-mesher> beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.02 seconds)
container-test-run-data-mesher> alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> beta # [1582752.029796] beta data-mesher[213]: time=2026-09-22T15:06:12.015Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta # [1582756.647409] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [1582756.647409] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [1582756.648074] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [1582756.648074] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> beta # [1582756.649956] beta data-mesher[213]: time=2026-09-22T15:06:16.635Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582756.650000] beta data-mesher[213]: time=2026-09-22T15:06:16.635Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [1582756.650058] beta data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [1582756.647436] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [1582756.647436] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> alpha # [1582756.648353] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582756.648353] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582756.648530] alpha data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [1582756.648608] alpha data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s
container-test-run-data-mesher> alpha # [1582756.648608] alpha data-mesher[213]: time=2026-09-22T15:06:16.634Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [1582756.649599] alpha data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true
container-test-run-data-mesher> gamma # [1582756.647409] gamma data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [1582756.647921] gamma data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [1582756.648162] gamma data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [1582756.648214] gamma data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [1582756.648325] gamma data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> gamma # [1582756.649268] gamma data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true
container-test-run-data-mesher> gamma # [1582756.649268] gamma data-mesher[213]: time=2026-09-22T15:06:16.635Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> gamma # [1582756.649332] gamma data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)
container-test-run-data-mesher> beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)
container-test-run-data-mesher> alpha: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub
container-test-run-data-mesher> alpha: (finished: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 0.01 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [1582757.133108] alpha data-mesher[213]: time=2026-09-22T15:06:17.118Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=204
container-test-run-data-mesher> beta # [1582761.649803] beta data-mesher[213]: time=2026-09-22T15:06:21.635Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582761.649803] beta data-mesher[213]: time=2026-09-22T15:06:21.635Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.635Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582761.651664] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> beta # [1582761.651664] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> beta # [1582761.651664] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> beta # [1582761.651910] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [1582761.652596] beta data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=2.290032ms
container-test-run-data-mesher> alpha # [1582761.649086] alpha data-mesher[213]: time=2026-09-22T15:06:21.634Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [1582761.649086] alpha data-mesher[213]: time=2026-09-22T15:06:21.634Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [1582761.650445] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582761.650536] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s
container-test-run-data-mesher> alpha # [1582761.650536] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [1582761.650741] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582761.650741] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [1582761.651123] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC
container-test-run-data-mesher> alpha # [1582761.651572] alpha data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [1582761.651572] alpha data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC
container-test-run-data-mesher> alpha # [1582761.651725] alpha data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [1582761.652841] alpha data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha # [1582761.654749] alpha data-mesher[213]: time=2026-09-22T15:06:21.640Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma # [1582761.650436] gamma data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [1582761.650436] gamma data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s
container-test-run-data-mesher> gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg
container-test-run-data-mesher> gamma # [1582761.656082] gamma data-mesher[213]: time=2026-09-22T15:06:21.641Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=3.61049ms
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 5.05 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 0.01 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)
container-test-run-data-mesher> alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub
container-test-run-data-mesher> Error: failed to update file: 403 Forbidden, signer Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE= is not authorized for this file test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE
container-test-run-data-mesher> alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub, in 0.03 seconds)
container-test-run-data-mesher> (finished: run the VM test script, in 38.55 seconds)
container-test-run-data-mesher> alpha # [1582762.238460] alpha data-mesher[213]: time=2026-09-22T15:06:22.224Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=403
container-test-run-data-mesher> test script finished in 39.12s
container-test-run-data-mesher> cleanup
container-test-run-data-mesher> kill NspawnMachine (pid 54)
container-test-run-data-mesher> kill NspawnMachine (pid 53)
container-test-run-data-mesher> kill NspawnMachine (pid 55)
container-test-run-data-mesher> Container alpha terminated by signal KILL.
container-test-run-data-mesher> Container beta terminated by signal KILL.
container-test-run-data-mesher> (finished: cleanup, in 0.44 seconds)
container-test-run-data-mesher> Container gamma terminated by signal KILL.
warning: SQLite database '/nix/var/nix/db/db.sqlite' is busy

post-build step Upload to niks3: ok
time=2026-09-22T15:06:28.866Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-09-22T15:06:29.401Z level=INFO msg="Uploading 1 narinfos"
time=2026-09-22T15:06:29.890Z level=INFO msg="Upload complete. (1.088s)"