this derivation will be built:
  /nix/store/vjrvvgmvj7smmqy3w33jx8inajr313br-container-test-run-data-mesher.drv
building '/nix/store/vjrvvgmvj7smmqy3w33jx8inajr313br-container-test-run-data-mesher.drv' on 'ssh-ng://builder@build-x86-01.clan.lol'
building '/nix/store/vjrvvgmvj7smmqy3w33jx8inajr313br-container-test-run-data-mesher.drv'
container-test-run-data-mesher> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-data-mesher> start all VLans
container-test-run-data-mesher> (finished: start all VLans, in 0.00 seconds)
container-test-run-data-mesher> 
container-test-run-data-mesher> Test will time out and terminate in 3600.0 seconds
container-test-run-data-mesher> run the VM test script
container-test-run-data-mesher> additionally exposed symbols:
container-test-run-data-mesher>     alpha, beta, gamma,
container-test-run-data-mesher>     vlan1,
container-test-run-data-mesher>     start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh
container-test-run-data-mesher> start all VMs
container-test-run-data-mesher> alpha: systemd-nspawn running (pid 53)
container-test-run-data-mesher> alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...
container-test-run-data-mesher> gamma: systemd-nspawn running (pid 55)
container-test-run-data-mesher> beta: systemd-nspawn running (pid 54)
container-test-run-data-mesher> gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...
container-test-run-data-mesher> beta: Waiting for journal at /build/vm-state-beta/var/log/journal...
container-test-run-data-mesher> (finished: start all VMs, in 0.00 seconds)
container-test-run-data-mesher> alpha: waiting for unit data-mesher.service
container-test-run-data-mesher> nixos-nspawn(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(alpha): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(alpha): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-nspawn(beta): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.
container-test-run-data-mesher> ░ Spawning container alpha on /build/vm-state-alpha.
container-test-run-data-mesher> ░ Spawning container gamma on /build/vm-state-gamma.
container-test-run-data-mesher> ░ Spawning container beta on /build/vm-state-beta.
container-test-run-data-mesher> alpha # [114321.776587] alpha systemd-journald[87]: Journal started
container-test-run-data-mesher> alpha # [114321.776617] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/fd35b7e0fff04bbb855058156b99231d) is 8M, max 3.7G, 3.7G free.
container-test-run-data-mesher> alpha # [114321.779399] alpha systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> alpha # [114321.779871] alpha systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> alpha # [114321.780263] alpha systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> alpha # [114321.785517] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/fd35b7e0fff04bbb855058156b99231d is 1.310ms for 5 entries.
container-test-run-data-mesher> alpha # [114321.785517] alpha systemd-journald[87]: System Journal (/var/log/journal/fd35b7e0fff04bbb855058156b99231d) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> alpha # [114321.789766] alpha systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> alpha # [114321.789916] alpha systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> alpha # [114321.789976] alpha systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> alpha # [114321.790573] alpha systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [114321.790607] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [114321.791079] alpha systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> alpha # [114321.791095] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [114321.844085] alpha systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> alpha # [114321.844660] alpha systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [114321.857593] alpha systemd-tmpfiles[154]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [114321.857789] alpha systemd-tmpfiles[154]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [114321.857927] alpha systemd-tmpfiles[154]: fchmod() of /var/log/journal/fd35b7e0fff04bbb855058156b99231d failed: Operation not permitted
container-test-run-data-mesher> alpha # [114321.858138] alpha systemd-tmpfiles[154]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [114321.859358] alpha systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [114321.860293] alpha systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> alpha # [114321.860907] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [114321.868860] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [114321.877861] alpha systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [114321.878665] alpha systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> alpha # [114321.885112] alpha systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> alpha # [114321.895622] alpha systemd[1]: Finished Firewall.
container-test-run-data-mesher> alpha # [114321.895684] alpha systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> alpha # [114321.895833] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [114321.896427] alpha systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [114322.207733] alpha systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [114322.207810] alpha systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [114322.212837] alpha systemd-networkd[204]: /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 # [114322.212985] alpha systemd-networkd[204]: /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 # [114322.213065] alpha systemd-networkd[204]: lo: Link UP
container-test-run-data-mesher> alpha # [114322.213068] alpha systemd-networkd[204]: lo: Gained carrier
container-test-run-data-mesher> alpha # [114322.213217] alpha systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [114322.213473] alpha systemd[1]: Started Network Management.
container-test-run-data-mesher> gamma # [114321.775926] gamma systemd-journald[87]: Journal started
container-test-run-data-mesher> gamma # [114321.775970] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/7c9b47eb2e5843f59a78347ddec62164) is 8M, max 3.7G, 3.7G free.
container-test-run-data-mesher> gamma # [114321.777494] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> gamma # [114321.781716] gamma systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> alpha # [114322.213511] alpha systemd-networkd[204]: eth1: Link UP
container-test-run-data-mesher> gamma # [114321.782233] gamma systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> alpha # [114322.213641] alpha systemd-networkd[204]: eth1: Gained carrier
container-test-run-data-mesher> gamma # [114321.782675] gamma systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> alpha # [114322.214267] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> alpha # [114322.234102] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> gamma # [114321.787658] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/7c9b47eb2e5843f59a78347ddec62164 is 1.248ms for 6 entries.
container-test-run-data-mesher> gamma # [114321.787658] gamma systemd-journald[87]: System Journal (/var/log/journal/7c9b47eb2e5843f59a78347ddec62164) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> gamma # [114321.792164] gamma systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [114321.792273] gamma systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> gamma # [114321.792312] gamma systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [114321.792681] gamma systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> gamma # [114321.792702] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [114321.792996] gamma systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> gamma # [114321.793018] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [114321.849215] gamma systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> gamma # [114321.849853] gamma systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> gamma # [114321.860331] gamma systemd-tmpfiles[159]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [114321.860478] gamma systemd-tmpfiles[159]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [114321.860581] gamma systemd-tmpfiles[159]: fchmod() of /var/log/journal/7c9b47eb2e5843f59a78347ddec62164 failed: Operation not permitted
container-test-run-data-mesher> gamma # [114321.860734] gamma systemd-tmpfiles[159]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [114321.861629] gamma systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [114321.862315] gamma systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [114321.862728] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [114321.870730] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> gamma # [114321.877155] gamma systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> gamma # [114321.877761] gamma systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [114321.885563] gamma systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [114321.896663] gamma systemd[1]: Finished Firewall.
container-test-run-data-mesher> gamma # [114321.896805] gamma systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [114321.897030] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> gamma # [114321.897888] gamma systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [114322.174093] gamma systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [114322.174176] gamma systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [114322.179268] gamma systemd-networkd[204]: /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 # [114322.179415] gamma systemd-networkd[204]: /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 # [114322.179497] gamma systemd-networkd[204]: lo: Link UP
container-test-run-data-mesher> gamma # [114322.179499] gamma systemd-networkd[204]: lo: Gained carrier
container-test-run-data-mesher> gamma # [114322.179656] gamma systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> gamma # [114322.179916] gamma systemd[1]: Started Network Management.
container-test-run-data-mesher> gamma # [114322.179972] gamma systemd-networkd[204]: eth1: Link UP
container-test-run-data-mesher> gamma # [114322.180123] gamma systemd-networkd[204]: eth1: Gained carrier
container-test-run-data-mesher> gamma # [114322.180789] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> gamma # [114322.213634] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [114321.785361] beta systemd-journald[88]: Journal started
container-test-run-data-mesher> beta # [114321.785400] beta systemd-journald[88]: Runtime Journal (/run/log/journal/af7e68816eeb4ee994bad060de9023d2) is 8M, max 3.7G, 3.7G free.
container-test-run-data-mesher> beta # [114321.789674] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> beta # [114321.797965] beta systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [114321.798299] beta systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [114321.798644] beta systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [114321.803488] beta systemd-journald[88]: Time spent on flushing to /var/log/journal/af7e68816eeb4ee994bad060de9023d2 is 1.086ms for 6 entries.
container-test-run-data-mesher> beta # [114321.803488] beta systemd-journald[88]: System Journal (/var/log/journal/af7e68816eeb4ee994bad060de9023d2) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [114321.835682] beta systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> beta # [114321.835823] beta systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [114321.835862] beta systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> beta # [114321.836256] beta systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> beta # [114321.836283] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [114321.836620] beta systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> beta # [114321.836635] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> beta # [114321.849249] beta systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> beta # [114321.849867] beta systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> beta # [114321.860616] beta systemd-tmpfiles[160]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [114321.860811] beta systemd-tmpfiles[160]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [114321.860946] beta systemd-tmpfiles[160]: fchmod() of /var/log/journal/af7e68816eeb4ee994bad060de9023d2 failed: Operation not permitted
container-test-run-data-mesher> beta # [114321.861149] beta systemd-tmpfiles[160]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> beta # [114321.862456] beta systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> beta # [114321.863296] beta systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> beta # [114321.863858] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> beta # [114321.874043] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> beta # [114321.879523] beta systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> beta # [114321.880354] beta systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> beta # [114321.885731] beta systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> beta # [114321.896208] beta systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [114321.896280] beta systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> beta # [114321.896450] beta systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> beta # [114321.897197] beta systemd[1]: Starting Network Management...
container-test-run-data-mesher> beta # [114322.192374] beta systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [114322.192450] beta systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> beta # [114322.197555] beta systemd-networkd[205]: /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 # [114322.197701] beta systemd-networkd[205]: /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 # [114322.197787] beta systemd-networkd[205]: lo: Link UP
container-test-run-data-mesher> beta # [114322.197790] beta systemd-networkd[205]: lo: Gained carrier
container-test-run-data-mesher> beta # [114322.197940] beta systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [114322.198219] beta systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [114322.207265] beta systemd-networkd[205]: eth1: Link UP
container-test-run-data-mesher> beta # [114322.207273] beta systemd-networkd[205]: eth1: Gained carrier
container-test-run-data-mesher> beta # [114322.207311] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [114322.227692] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [114322.393774] beta systemd-resolved[118]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [114322.393785] beta systemd-resolved[118]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> beta # [114322.393789] beta systemd-resolved[118]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> beta # [114322.393806] beta systemd-resolved[118]: 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 # [114322.410404] beta systemd-resolved[118]: Using system hostname 'beta'.
container-test-run-data-mesher> beta # [114322.411688] beta systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> beta # [114322.411772] beta systemd[1]: Reached target Network.
container-test-run-data-mesher> beta # [114322.411822] beta systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> beta # [114322.411863] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [114322.411885] beta systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> beta # [114322.411900] beta systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [114322.412023] beta systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> beta # [114322.412112] beta systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> beta # [114322.412208] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> beta # [114322.412222] beta systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [114322.412251] beta systemd[1]: Reached target Basic System.
container-test-run-data-mesher> beta # [114322.413235] beta systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> beta # [114322.413665] beta systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> beta # [114322.414122] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> beta # [114322.414868] beta systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> beta # [114322.539023] beta nsncd[212]: Sep 05 15:02:16.916 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> beta # [114322.493448] beta systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> beta # [114322.493500] beta systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> beta # [114322.493545] beta systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> beta # [114322.539573] beta systemd[1]: Starting User Login Management...
container-test-run-data-mesher> gamma # [114322.324638] gamma systemd-resolved[109]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [114322.324647] gamma systemd-resolved[109]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [114322.324651] gamma systemd-resolved[109]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [114322.324669] gamma systemd-resolved[109]: 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 # [114322.336297] alpha systemd-resolved[109]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [114322.335248] gamma systemd-resolved[109]: Using system hostname 'gamma'.
container-test-run-data-mesher> alpha # [114322.336304] alpha systemd-resolved[109]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [114322.336241] gamma systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> alpha # [114322.336309] alpha systemd-resolved[109]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [114322.336321] gamma systemd[1]: Reached target Network.
container-test-run-data-mesher> alpha # [114322.336336] alpha systemd-resolved[109]: 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 # [114322.336377] gamma systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> alpha # [114322.350231] alpha systemd-resolved[109]: Using system hostname 'alpha'.
container-test-run-data-mesher> gamma # [114322.336424] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [114322.336450] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [114322.351167] alpha systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [114322.336467] gamma systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [114322.351224] alpha systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [114322.336584] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> alpha # [114322.351263] alpha systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [114322.336670] gamma systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [114322.351300] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [114322.336762] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> alpha # [114322.351317] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [114322.351328] alpha systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [114322.351412] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> gamma # [114322.336777] gamma systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> alpha # [114322.351493] alpha systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [114322.351578] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [114322.336809] gamma systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [114322.337916] gamma systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> alpha # [114322.351591] alpha systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> alpha # [114322.351612] alpha systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [114322.338329] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> alpha # [114322.353258] alpha systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [114322.338794] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> gamma # [114322.339623] gamma systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [114322.353839] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [114322.362436] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> gamma # [114322.539461] gamma nsncd[211]: Sep 05 15:02:16.873 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> gamma # [114322.449756] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> gamma # [114322.449797] gamma systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> gamma # [114322.449826] gamma systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> gamma # [114322.539897] gamma systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [114322.354304] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> alpha # [114322.355108] alpha systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [114322.364447] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [114322.436958] alpha nsncd[211]: Sep 05 15:02:16.860 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [114322.437156] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [114322.437219] alpha systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [114322.437263] alpha systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> alpha # [114322.438230] alpha systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [114322.438708] alpha systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> alpha # [114322.444653] alpha systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> alpha # [114322.445305] alpha systemd[1]: Started Console Getty.
container-test-run-data-mesher> alpha # [114322.539721] alpha dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> alpha # [114322.539721] alpha dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> alpha # [114322.539721] alpha dbus-broker-launch[212]: Invalid user-name in /nix/store/12jm5b0dsxv264wzabjwd6ia3hs3fiwd-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> alpha # [114322.445333] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> alpha # [114322.445344] alpha systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> alpha # [114322.500523] alpha systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> gamma # [114322.540999] gamma systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [114322.574099] gamma systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> gamma # [114322.574965] gamma systemd[1]: Started Console Getty.
container-test-run-data-mesher> gamma # [114322.575007] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> gamma # [114322.575025] gamma systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [114322.600511] gamma dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> gamma # [114322.601011] gamma dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> gamma # [114322.601011] gamma dbus-broker-launch[212]: Invalid user-name in /nix/store/12jm5b0dsxv264wzabjwd6ia3hs3fiwd-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> gamma # [114322.601345] gamma systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> gamma # [114322.605141] gamma dbus-broker-launch[212]: Ready
container-test-run-data-mesher> gamma # [114322.685775] gamma data-mesher[209]: time=2026-09-05T15:02:17.109Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> gamma # [114322.686241] gamma data-mesher[209]: time=2026-09-05T15:02:17.109Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo: [/dns/alpha.clan/tcp/7946]} {12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> gamma # [114322.686272] gamma data-mesher[209]: time=2026-09-05T15:02:17.109Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> gamma # [114322.796581] gamma data-mesher[209]: time=2026-09-05T15:02:17.219Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> gamma # [114322.796640] gamma data-mesher[209]: time=2026-09-05T15:02:17.219Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> alpha # [114322.503812] alpha dbus-broker-launch[212]: Ready
container-test-run-data-mesher> beta # [114322.541035] beta systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> beta # [114322.541259] beta systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> beta # [114322.574088] beta systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> beta # [114322.574953] beta systemd[1]: Started Console Getty.
container-test-run-data-mesher> beta # [114322.574982] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> beta # [114322.575000] beta systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> beta # [114322.590418] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [114322.590987] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> beta # [114322.590987] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/12jm5b0dsxv264wzabjwd6ia3hs3fiwd-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [114322.591363] beta systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> beta # [114322.596650] beta dbus-broker-launch[213]: Ready
container-test-run-data-mesher> alpha # [114322.797048] alpha data-mesher[209]: time=2026-09-05T15:02:17.220Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> alpha # [114322.798429] alpha data-mesher[209]: time=2026-09-05T15:02:17.221Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo: [/dns/alpha.clan/tcp/7946]} {12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> alpha # [114322.798429] alpha data-mesher[209]: time=2026-09-05T15:02:17.221Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> alpha # [114322.804149] alpha data-mesher[209]: time=2026-09-05T15:02:17.227Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> alpha # [114322.804212] alpha data-mesher[209]: time=2026-09-05T15:02:17.227Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> alpha # [114322.806326] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="libp2p host created" peer_id=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo 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 # [114322.806379] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> alpha # [114322.806379] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> alpha # [114322.806379] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> alpha # [114322.806379] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [114322.806446] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [114322.806469] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [114322.806492] alpha data-mesher[209]: time=2026-09-05T15:02:17.229Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [114322.808883] alpha data-mesher[209]: time=2026-09-05T15:02:17.232Z level=INFO msg="peer connected" peer_id=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> alpha # [114322.809416] alpha data-mesher[209]: time=2026-09-05T15:02:17.232Z level=INFO msg="peer connected" peer_id=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB remote_addr=/ip4/192.168.1.2/tcp/43050
container-test-run-data-mesher> alpha # [114322.874459] alpha systemd-logind[221]: New seat seat0.
container-test-run-data-mesher> alpha # [114322.874599] alpha systemd[1]: Started User Login Management.
container-test-run-data-mesher> alpha # [114322.875423] alpha systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> alpha # [114322.902419] alpha systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> alpha # [114322.902557] alpha systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> beta # [114322.797118] beta data-mesher[210]: time=2026-09-05T15:02:17.220Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> beta # [114322.797453] beta data-mesher[210]: time=2026-09-05T15:02:17.220Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo: [/dns/alpha.clan/tcp/7946]} {12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> beta # [114322.797477] beta data-mesher[210]: time=2026-09-05T15:02:17.220Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> beta # [114322.803766] beta data-mesher[210]: time=2026-09-05T15:02:17.227Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [114322.803942] beta data-mesher[210]: time=2026-09-05T15:02:17.227Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [114322.806433] beta data-mesher[210]: time=2026-09-05T15:02:17.229Z level=INFO msg="libp2p host created" peer_id=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB 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 # [114322.806459] beta data-mesher[210]: time=2026-09-05T15:02:17.229Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> beta # [114322.806459] beta data-mesher[210]: time=2026-09-05T15:02:17.229Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> beta # [114322.806459] beta data-mesher[210]: time=2026-09-05T15:02:17.229Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> beta # [114322.806505] beta data-mesher[210]: time=2026-09-05T15:02:17.229Z level=INFO msg="starting server"
container-test-run-data-mesher> beta # [114322.806576] beta data-mesher[210]: time=2026-09-05T15:02:17.229Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> beta # [114322.806647] beta data-mesher[210]: time=2026-09-05T15:02:17.229Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> beta # [114322.806715] beta data-mesher[210]: time=2026-09-05T15:02:17.230Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> beta # [114322.808917] beta data-mesher[210]: time=2026-09-05T15:02:17.232Z level=INFO msg="peer connected" peer_id=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [114322.809136] beta data-mesher[210]: time=2026-09-05T15:02:17.232Z level=INFO msg="peer connected" peer_id=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [114322.893677] beta systemd-logind[228]: New seat seat0.
container-test-run-data-mesher> beta # [114322.893817] beta systemd[1]: Started User Login Management.
container-test-run-data-mesher> beta # [114322.897258] beta systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> beta # [114322.905107] beta systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> beta # [114322.905182] beta systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> gamma # [114322.800774] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="libp2p host created" peer_id=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 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 # [114322.800840] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> gamma # [114322.800840] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> gamma # [114322.800840] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> gamma # [114322.800840] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="starting server"
container-test-run-data-mesher> gamma # [114322.800939] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> gamma # [114322.800962] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> gamma # [114322.801010] gamma data-mesher[209]: time=2026-09-05T15:02:17.224Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> gamma # [114322.882292] gamma systemd-logind[228]: New seat seat0.
container-test-run-data-mesher> gamma # [114322.882405] gamma systemd[1]: Started User Login Management.
container-test-run-data-mesher> gamma # [114322.897351] gamma systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [114322.906462] gamma systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> gamma # [114322.906515] gamma systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> beta # [114323.649110] beta systemd-networkd[205]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha # [114323.649220] alpha systemd-networkd[204]: eth1: Gained IPv6LL
container-test-run-data-mesher> gamma # [114323.841544] gamma systemd-networkd[204]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha # [114327.809802] alpha data-mesher[209]: time=2026-09-05T15:02:22.233Z level=INFO msg="peer connected" peer_id=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> beta # [114327.807016] beta data-mesher[210]: time=2026-09-05T15:02:22.230Z level=INFO msg="peer connected" peer_id=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 remote_addr=/ip6/2001:db8:1::3/tcp/7946
container-test-run-data-mesher> beta # [114327.945941] beta data-mesher[210]: time=2026-09-05T15:02:22.230Z level=INFO msg="peer connected" peer_id=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> gamma # [114327.806823] gamma data-mesher[209]: time=2026-09-05T15:02:22.230Z level=INFO msg="peer connected" peer_id=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB remote_addr=/ip6/2001:db8:1::2/tcp/7946
container-test-run-data-mesher> gamma # [114327.806823] gamma data-mesher[209]: time=2026-09-05T15:02:22.230Z level=INFO msg="peer connected" peer_id=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> gamma # [114327.946023] gamma data-mesher[209]: time=2026-09-05T15:02:22.232Z level=INFO msg="peer connected" peer_id=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> alpha: still waiting for container 'alpha' to reach ready state...
container-test-run-data-mesher> alpha # [114332.801442] alpha data-mesher[209]: time=2026-09-05T15:02:27.224Z level=INFO msg="received state sync from peer" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114332.801442] alpha data-mesher[209]: time=2026-09-05T15:02:27.224Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=INFO msg="state exchange complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=INFO msg="received state sync from peer" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114332.912918] alpha data-mesher[209]: time=2026-09-05T15:02:27.230Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [114332.807353] alpha systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [114332.807491] alpha systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [114332.807142] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> beta # [114332.807142] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> beta # [114332.807142] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=INFO msg="received state sync from peer" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114332.912891] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114332.912891] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114332.912891] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=INFO msg="state exchange complete" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> beta # [114332.912891] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=INFO msg="server started"
container-test-run-data-mesher> beta # [114332.912891] beta data-mesher[210]: time=2026-09-05T15:02:27.230Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> beta # [114332.807480] beta systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> beta # [114332.807628] beta systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> gamma # [114332.801079] gamma data-mesher[209]: time=2026-09-05T15:02:27.224Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [114332.801079] gamma data-mesher[209]: time=2026-09-05T15:02:27.224Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114332.912959] gamma data-mesher[209]: time=2026-09-05T15:02:27.224Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114332.912959] gamma data-mesher[209]: time=2026-09-05T15:02:27.224Z level=INFO msg="state exchange complete" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114332.912959] gamma data-mesher[209]: time=2026-09-05T15:02:27.224Z level=INFO msg="server started"
container-test-run-data-mesher> gamma # [114332.912959] gamma data-mesher[209]: time=2026-09-05T15:02:27.224Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> gamma # [114332.801686] gamma systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> gamma # [114332.801863] gamma systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> alpha # [114337.810075] alpha data-mesher[209]: time=2026-09-05T15:02:32.233Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [114337.810075] alpha data-mesher[209]: time=2026-09-05T15:02:32.233Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> alpha # [114337.810399] alpha data-mesher[209]: time=2026-09-05T15:02:32.233Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114337.810399] alpha data-mesher[209]: time=2026-09-05T15:02:32.233Z level=INFO msg="state exchange complete" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> alpha # [114337.810489] alpha data-mesher[209]: time=2026-09-05T15:02:32.233Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [114337.925890] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> alpha # [114337.926608] alpha systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> alpha # [114337.926741] alpha systemd[1]: Startup finished in 16.410s.
container-test-run-data-mesher> beta # [114337.805257] beta data-mesher[210]: time=2026-09-05T15:02:32.228Z level=INFO msg="received state sync from peer" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> beta # [114337.805257] beta data-mesher[210]: time=2026-09-05T15:02:32.228Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> beta # [114337.807603] beta data-mesher[210]: time=2026-09-05T15:02:32.230Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [114337.807603] beta data-mesher[210]: time=2026-09-05T15:02:32.230Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> beta # [114337.807895] beta data-mesher[210]: time=2026-09-05T15:02:32.231Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> beta # [114337.807895] beta data-mesher[210]: time=2026-09-05T15:02:32.231Z level=INFO msg="state exchange complete" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> beta # [114337.807951] beta data-mesher[210]: time=2026-09-05T15:02:32.231Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [114337.926118] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> beta # [114337.926636] beta systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> beta # [114337.930030] beta systemd[1]: Startup finished in 16.417s.
container-test-run-data-mesher> gamma # [114337.804834] gamma data-mesher[209]: time=2026-09-05T15:02:32.228Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [114337.804834] gamma data-mesher[209]: time=2026-09-05T15:02:32.228Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> gamma # [114337.805390] gamma data-mesher[209]: time=2026-09-05T15:02:32.228Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> gamma # [114337.805390] gamma data-mesher[209]: time=2026-09-05T15:02:32.228Z level=INFO msg="state exchange complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> gamma # [114337.805486] gamma data-mesher[209]: time=2026-09-05T15:02:32.228Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [114337.807795] gamma data-mesher[209]: time=2026-09-05T15:02:32.231Z level=INFO msg="received state sync from peer" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> gamma # [114337.807795] gamma data-mesher[209]: time=2026-09-05T15:02:32.231Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> gamma # [114337.810290] gamma data-mesher[209]: time=2026-09-05T15:02:32.233Z level=INFO msg="received state sync from peer" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114337.810290] gamma data-mesher[209]: time=2026-09-05T15:02:32.233Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114337.926257] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [114337.926808] gamma systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [114337.930040] gamma systemd[1]: Startup finished in 16.413s.
container-test-run-data-mesher> alpha: (finished: waiting for unit data-mesher.service, in 17.15 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.00 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.08 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/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-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/g6gf61a5yqfnj1warmxc3ks7i9vb4a54-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.00 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> alpha # [114338.479995] alpha data-mesher[209]: time=2026-09-05T15:02:32.903Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> alpha # [114342.806536] alpha data-mesher[209]: time=2026-09-05T15:02:37.229Z level=INFO msg="received state sync from peer" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114342.806536] alpha data-mesher[209]: time=2026-09-05T15:02:37.229Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114342.806919] alpha data-mesher[209]: time=2026-09-05T15:02:37.230Z level=INFO msg="received file request" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_file
container-test-run-data-mesher> alpha # [114342.807893] alpha data-mesher[209]: time=2026-09-05T15:02:37.231Z level=INFO msg="file transfer complete" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_file
container-test-run-data-mesher> alpha # [114342.808203] alpha data-mesher[209]: time=2026-09-05T15:02:37.231Z level=INFO msg="received state sync from peer" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114342.808224] alpha data-mesher[209]: time=2026-09-05T15:02:37.231Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114342.808470] alpha data-mesher[209]: time=2026-09-05T15:02:37.231Z level=INFO msg="received file request" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_file
container-test-run-data-mesher> alpha # [114342.808577] alpha data-mesher[209]: time=2026-09-05T15:02:37.231Z level=INFO msg="file transfer complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_file
container-test-run-data-mesher> alpha # [114342.810614] alpha data-mesher[209]: time=2026-09-05T15:02:37.233Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [114342.928669] alpha data-mesher[209]: time=2026-09-05T15:02:37.233Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> alpha # [114342.928669] alpha data-mesher[209]: time=2026-09-05T15:02:37.234Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114342.928669] alpha data-mesher[209]: time=2026-09-05T15:02:37.234Z level=INFO msg="state exchange complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> alpha # [114342.928669] alpha data-mesher[209]: time=2026-09-05T15:02:37.234Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [114342.806220] gamma data-mesher[209]: time=2026-09-05T15:02:37.229Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [114342.808040] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [114342.806220] gamma data-mesher[209]: time=2026-09-05T15:02:37.229Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> beta # [114342.808040] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114342.806687] gamma data-mesher[209]: time=2026-09-05T15:02:37.230Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114342.808308] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114342.806713] gamma data-mesher[209]: time=2026-09-05T15:02:37.230Z level=DEBUG msg="new file detected" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo name=test_file name=test_file
container-test-run-data-mesher> beta # [114342.808308] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=DEBUG msg="new file detected" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo name=test_file name=test_file
container-test-run-data-mesher> gamma # [114342.806713] gamma data-mesher[209]: time=2026-09-05T15:02:37.230Z level=INFO msg="state exchange complete" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114342.806759] gamma data-mesher[209]: time=2026-09-05T15:02:37.230Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [114342.808308] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=INFO msg="state exchange complete" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114342.806759] gamma data-mesher[209]: time=2026-09-05T15:02:37.230Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> beta # [114342.808308] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [114342.806787] gamma data-mesher[209]: time=2026-09-05T15:02:37.230Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-05 15:02:32.835 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114342.808374] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> gamma # [114342.928755] gamma data-mesher[209]: time=2026-09-05T15:02:37.352Z level=INFO msg="download complete" name=test_file signed_at="2026-09-05 15:02:32.835 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo written=true elapsed=121.945618ms
container-test-run-data-mesher> beta # [114342.808374] beta data-mesher[210]: time=2026-09-05T15:02:37.231Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-05 15:02:32.835 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114342.810743] beta data-mesher[210]: time=2026-09-05T15:02:37.234Z level=INFO msg="received state sync from peer" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114342.928669] beta data-mesher[210]: time=2026-09-05T15:02:37.234Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114342.928669] beta data-mesher[210]: time=2026-09-05T15:02:37.234Z level=DEBUG msg="new file detected" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo name=test_file name=test_file
container-test-run-data-mesher> beta # [114342.931849] beta data-mesher[210]: time=2026-09-05T15:02:37.355Z level=INFO msg="download complete" name=test_file signed_at="2026-09-05 15:02:32.835 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo written=true elapsed=123.489475ms
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.02 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.00 seconds)
container-test-run-data-mesher> beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-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/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)
container-test-run-data-mesher> alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> beta # [114343.537089] beta data-mesher[210]: time=2026-09-05T15:02:37.960Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> alpha # [114347.810836] alpha data-mesher[209]: time=2026-09-05T15:02:42.234Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [114347.811050] alpha data-mesher[209]: time=2026-09-05T15:02:42.234Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> alpha # [114347.811172] alpha data-mesher[209]: time=2026-09-05T15:02:42.234Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114347.811172] alpha data-mesher[209]: time=2026-09-05T15:02:42.234Z level=INFO msg="state exchange complete" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> alpha # [114347.811252] alpha data-mesher[209]: time=2026-09-05T15:02:42.234Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [114347.807465] beta data-mesher[210]: time=2026-09-05T15:02:42.230Z level=INFO msg="received state sync from peer" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> gamma # [114347.807052] gamma data-mesher[209]: time=2026-09-05T15:02:42.230Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [114347.807465] beta data-mesher[210]: time=2026-09-05T15:02:42.230Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> gamma # [114347.807052] gamma data-mesher[209]: time=2026-09-05T15:02:42.230Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> beta # [114347.808511] beta data-mesher[210]: time=2026-09-05T15:02:42.231Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [114347.807579] gamma data-mesher[209]: time=2026-09-05T15:02:42.230Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> beta # [114347.808511] beta data-mesher[210]: time=2026-09-05T15:02:42.231Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> gamma # [114347.808707] gamma data-mesher[209]: time=2026-09-05T15:02:42.232Z level=INFO msg="received state sync from peer" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> beta # [114347.859530] beta data-mesher[210]: time=2026-09-05T15:02:42.282Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> gamma # [114347.808707] gamma data-mesher[209]: time=2026-09-05T15:02:42.232Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> beta # [114347.859530] beta data-mesher[210]: time=2026-09-05T15:02:42.282Z level=INFO msg="state exchange complete" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> gamma # [114347.811038] gamma data-mesher[209]: time=2026-09-05T15:02:42.234Z level=INFO msg="received state sync from peer" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114347.859627] beta data-mesher[210]: time=2026-09-05T15:02:42.282Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [114347.811059] gamma data-mesher[209]: time=2026-09-05T15:02:42.234Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114347.859351] gamma data-mesher[209]: time=2026-09-05T15:02:42.282Z level=DEBUG msg="imported tombstone" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB name=test_file written=true
container-test-run-data-mesher> gamma # [114347.859351] gamma data-mesher[209]: time=2026-09-05T15:02:42.282Z level=INFO msg="state exchange complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> gamma # [114347.859474] gamma data-mesher[209]: time=2026-09-05T15:02:42.282Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [114347.859474] gamma data-mesher[209]: time=2026-09-05T15:02:42.282Z level=DEBUG msg="imported tombstone" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB name=test_file written=false
container-test-run-data-mesher> alpha # [114352.811537] alpha data-mesher[209]: time=2026-09-05T15:02:47.234Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [114352.811913] alpha data-mesher[209]: time=2026-09-05T15:02:47.234Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> alpha # [114352.811913] alpha data-mesher[209]: time=2026-09-05T15:02:47.235Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114352.860085] alpha data-mesher[209]: time=2026-09-05T15:02:47.283Z level=INFO msg="received state sync from peer" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114352.860085] alpha data-mesher[209]: time=2026-09-05T15:02:47.283Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114352.878567] alpha data-mesher[209]: time=2026-09-05T15:02:47.301Z level=DEBUG msg="imported tombstone" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB name=test_file written=true
container-test-run-data-mesher> alpha # [114352.878567] alpha data-mesher[209]: time=2026-09-05T15:02:47.301Z level=INFO msg="state exchange complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> alpha # [114352.878624] alpha data-mesher[209]: time=2026-09-05T15:02:47.301Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [114352.878647] alpha data-mesher[209]: time=2026-09-05T15:02:47.301Z level=DEBUG msg="imported tombstone" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB name=test_file written=false
container-test-run-data-mesher> beta # [114352.811801] beta data-mesher[210]: time=2026-09-05T15:02:47.235Z level=INFO msg="received state sync from peer" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114352.860123] gamma data-mesher[209]: time=2026-09-05T15:02:47.283Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [114352.811801] beta data-mesher[210]: time=2026-09-05T15:02:47.235Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114352.859891] beta data-mesher[210]: time=2026-09-05T15:02:47.283Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [114352.878240] beta data-mesher[210]: time=2026-09-05T15:02:47.283Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114352.878270] gamma data-mesher[209]: time=2026-09-05T15:02:47.283Z level=DEBUG msg="initiating state exchange" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> gamma # [114352.878270] gamma data-mesher[209]: time=2026-09-05T15:02:47.283Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> beta # [114352.878240] beta data-mesher[210]: time=2026-09-05T15:02:47.283Z level=INFO msg="received state sync from peer" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> gamma # [114352.878270] gamma data-mesher[209]: time=2026-09-05T15:02:47.283Z level=INFO msg="state exchange complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB timeout=5s
container-test-run-data-mesher> beta # [114352.878240] beta data-mesher[210]: time=2026-09-05T15:02:47.283Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> gamma # [114352.878270] gamma data-mesher[209]: time=2026-09-05T15:02:47.283Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [114352.878713] beta data-mesher[210]: time=2026-09-05T15:02:47.302Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114352.878750] beta data-mesher[210]: time=2026-09-05T15:02:47.302Z level=INFO msg="state exchange complete" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> beta # [114352.878750] beta data-mesher[210]: time=2026-09-05T15:02:47.302Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.04 seconds)
container-test-run-data-mesher> beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.00 seconds)
container-test-run-data-mesher> gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.00 seconds)
container-test-run-data-mesher> alpha: must succeed: cat /nix/store/pasb42dlwn7msc2ifpd5b51i2a00gkx2-per-machine-alpha-data-mesher-node-identity_identity.pub
container-test-run-data-mesher> alpha: (finished: must succeed: cat /nix/store/pasb42dlwn7msc2ifpd5b51i2a00gkx2-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.00 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.00 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-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/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.01 seconds)
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI, in 0.00 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> alpha # [114353.599529] alpha data-mesher[209]: time=2026-09-05T15:02:48.022Z level=INFO msg=http_request uri=/files/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI status=204
container-test-run-data-mesher> beta # [114357.879601] beta data-mesher[210]: time=2026-09-05T15:02:52.302Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [114357.879982] beta data-mesher[210]: time=2026-09-05T15:02:52.302Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> beta # [114357.882495] beta data-mesher[210]: time=2026-09-05T15:02:52.305Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114357.882619] beta data-mesher[210]: time=2026-09-05T15:02:52.305Z level=DEBUG msg="new file detected" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo name=test_file name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> beta # [114357.882655] beta data-mesher[210]: time=2026-09-05T15:02:52.305Z level=INFO msg="state exchange complete" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> beta # [114357.882655] beta data-mesher[210]: time=2026-09-05T15:02:52.305Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [114357.882700] beta data-mesher[210]: time=2026-09-05T15:02:52.306Z level=INFO msg="scheduling file download" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> beta # [114357.882727] beta data-mesher[210]: time=2026-09-05T15:02:52.306Z level=INFO msg="downloading file" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI signed_at="2026-09-05 15:02:48.022 +0000 UTC" signed_by="B37RQ0BGLkWQLnoNt/9TB8zHti1pF8aOxxSLl/Dr5NI=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> beta # [114357.918670] beta data-mesher[210]: time=2026-09-05T15:02:52.341Z level=INFO msg="download complete" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI signed_at="2026-09-05 15:02:48.022 +0000 UTC" signed_by="B37RQ0BGLkWQLnoNt/9TB8zHti1pF8aOxxSLl/Dr5NI=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo written=true elapsed=35.947966ms
container-test-run-data-mesher> gamma # [114357.861058] gamma data-mesher[209]: time=2026-09-05T15:02:52.284Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [114357.861494] gamma data-mesher[209]: time=2026-09-05T15:02:52.284Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114357.861908] gamma data-mesher[209]: time=2026-09-05T15:02:52.285Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114357.862046] gamma data-mesher[209]: time=2026-09-05T15:02:52.285Z level=DEBUG msg="new file detected" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo name=test_file name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> gamma # [114357.862046] gamma data-mesher[209]: time=2026-09-05T15:02:52.285Z level=INFO msg="state exchange complete" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo timeout=5s
container-test-run-data-mesher> gamma # [114357.862095] gamma data-mesher[209]: time=2026-09-05T15:02:52.285Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [114357.862115] gamma data-mesher[209]: time=2026-09-05T15:02:52.285Z level=INFO msg="scheduling file download" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> gamma # [114357.862157] gamma data-mesher[209]: time=2026-09-05T15:02:52.285Z level=INFO msg="downloading file" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI signed_at="2026-09-05 15:02:48.022 +0000 UTC" signed_by="B37RQ0BGLkWQLnoNt/9TB8zHti1pF8aOxxSLl/Dr5NI=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114357.882802] gamma data-mesher[209]: time=2026-09-05T15:02:52.305Z level=INFO msg="received state sync from peer" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114357.882802] gamma data-mesher[209]: time=2026-09-05T15:02:52.305Z level=INFO msg="merging remote state" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo
container-test-run-data-mesher> gamma # [114357.892256] gamma data-mesher[209]: time=2026-09-05T15:02:52.315Z level=INFO msg="download complete" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI signed_at="2026-09-05 15:02:48.022 +0000 UTC" signed_by="B37RQ0BGLkWQLnoNt/9TB8zHti1pF8aOxxSLl/Dr5NI=" peer=12D3KooWAKdBS3Ug3HiSCyqsGZotb9tJ2XdHxsy11jRH2NPoZaHo written=true elapsed=30.100913ms
container-test-run-data-mesher> alpha # [114357.861631] alpha data-mesher[209]: time=2026-09-05T15:02:52.284Z level=INFO msg="received state sync from peer" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114357.861631] alpha data-mesher[209]: time=2026-09-05T15:02:52.284Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114357.862565] alpha data-mesher[209]: time=2026-09-05T15:02:52.285Z level=INFO msg="received file request" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> alpha # [114357.864417] alpha data-mesher[209]: time=2026-09-05T15:02:52.287Z level=INFO msg="file transfer complete" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> alpha # [114357.882156] alpha data-mesher[209]: time=2026-09-05T15:02:52.305Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [114357.882257] alpha data-mesher[209]: time=2026-09-05T15:02:52.305Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> alpha # [114357.882307] alpha data-mesher[209]: time=2026-09-05T15:02:52.305Z level=INFO msg="received state sync from peer" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114357.882307] alpha data-mesher[209]: time=2026-09-05T15:02:52.305Z level=INFO msg="merging remote state" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB
container-test-run-data-mesher> alpha # [114357.883010] alpha data-mesher[209]: time=2026-09-05T15:02:52.306Z level=INFO msg="merging remote state" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5
container-test-run-data-mesher> alpha # [114357.883010] alpha data-mesher[209]: time=2026-09-05T15:02:52.306Z level=INFO msg="received file request" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> alpha # [114357.883010] alpha data-mesher[209]: time=2026-09-05T15:02:52.306Z level=INFO msg="state exchange complete" peer=12D3KooWHgbdFNQdKYRUreLZ3hQxAe8MfMHXxfFbMJYbUJ8EftB5 timeout=5s
container-test-run-data-mesher> alpha # [114357.883010] alpha data-mesher[209]: time=2026-09-05T15:02:52.306Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [114357.883947] alpha data-mesher[209]: time=2026-09-05T15:02:52.307Z level=INFO msg="file transfer complete" peer=12D3KooWLvLQcdBjD9RCPQp5BQ5KbBZks8WaZYfH7dBmfnoQuEDB network="MsEypbH92+gnYfVOynw9Mgi606bTy+7VE2hYx7Udqj0=" name=test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI, in 5.02 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI, in 0.00 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.00 seconds)
container-test-run-data-mesher> alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-shared-data-mesher-network_network.pub
container-test-run-data-mesher> Error: failed to update file: 403 Forbidden, signer B37RQ0BGLkWQLnoNt/9TB8zHti1pF8aOxxSLl/Dr5NI= is not authorized for this file test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI
container-test-run-data-mesher> alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1s4s9wmdj61yidpd7ah4shf8zzzvz8ql-shared-data-mesher-network_network.pub, in 0.01 seconds)
container-test-run-data-mesher> (finished: run the VM test script, in 37.40 seconds)
container-test-run-data-mesher> test script finished in 37.65s
container-test-run-data-mesher> cleanup
container-test-run-data-mesher> kill NspawnMachine (pid 53)
container-test-run-data-mesher> alpha # [114358.642570] alpha data-mesher[209]: time=2026-09-05T15:02:53.065Z level=INFO msg=http_request uri=/files/test_ns/B37RQ0BGLkWQLnoNt_9TB8zHti1pF8aOxxSLl_Dr5NI status=403
container-test-run-data-mesher> kill NspawnMachine (pid 54)
container-test-run-data-mesher> Container alpha terminated by signal KILL.
container-test-run-data-mesher> kill NspawnMachine (pid 55)
container-test-run-data-mesher> Container beta terminated by signal KILL.
container-test-run-data-mesher> Container gamma terminated by signal KILL.
container-test-run-data-mesher> (finished: cleanup, in 0.29 seconds)

post-build step Upload to niks3: ok
time=2026-09-05T15:02:53.885Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-09-05T15:02:54.329Z level=INFO msg="Uploading 1 narinfos"
time=2026-09-05T15:02:54.568Z level=INFO msg="Upload complete. (771ms)"