container-test-run-data-mesher
default.checks.aarch64-linux.data-mesher
· build #347
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 alpha, beta, gamma,9 vlan1,10 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_ssh11start all VMs12alpha: systemd-nspawn running (pid 53)13beta: systemd-nspawn running (pid 54)14alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...15gamma: systemd-nspawn running (pid 55)16beta: Waiting for journal at /build/vm-state-beta/var/log/journal...17gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...18(finished: start all VMs, in 0.00 seconds)19alpha: waiting for unit data-mesher.service20nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.22nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE23nixos-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.24nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE25nixos-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.26Note: 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.27Note: 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.28Note: 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.29░ Spawning container alpha on /build/vm-state-alpha.30░ Spawning container gamma on /build/vm-state-gamma.31░ Spawning container beta on /build/vm-state-beta.32alpha # No journal boot entry found for the specified boot (+0).33beta # No journal boot entry found for the specified boot (+0).34gamma # No journal boot entry found for the specified boot (+0).35beta # [5660982.121177] beta systemd-journald[87]: Journal started36beta # [5660982.121238] beta systemd-journald[87]: Runtime Journal (/run/log/journal/4326dd0ba69b45dab5e757d0f1604146) is 8M, max 2.5G, 2.4G free.37beta # [5660982.131465] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.38beta # [5660982.146853] beta systemd[1]: Starting Flush Journal to Persistent Storage...39beta # [5660982.147798] beta systemd[1]: Starting Network Name Resolution...40beta # [5660982.148528] beta systemd[1]: Starting Create Static Device Nodes in /dev...41beta # [5660982.156963] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/4326dd0ba69b45dab5e757d0f1604146 is 1.357ms for 6 entries.42beta # [5660982.156963] beta systemd-journald[87]: System Journal (/var/log/journal/4326dd0ba69b45dab5e757d0f1604146) is 8M, max 4G, 3.9G free.43beta # [5660982.177475] beta systemd[1]: Finished Create Static Device Nodes in /dev.44beta # [5660982.178382] beta systemd[1]: Reached target Preparation for Local File Systems.45beta # [5660982.178516] beta systemd[1]: Reached target Local File Systems.46beta # [5660982.179856] beta systemd[1]: Listening on Boot Loader Control Service Socket.47beta # [5660982.179956] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container48gamma # [5660982.110979] gamma systemd-journald[86]: Journal started49gamma # [5660982.111047] gamma systemd-journald[86]: Runtime Journal (/run/log/journal/824121db561f465fbba8d18d2c839bc3) is 8M, max 2.5G, 2.4G free.50beta # [5660982.180960] beta systemd[1]: Starting Save Transient machine-id to Disk...51gamma # [5660982.114070] gamma systemd[1]: Finished Apply Kernel Variables.52beta # [5660982.181074] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys53gamma # [5660982.125663] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.54beta # [5660982.264071] beta systemd[1]: Finished Flush Journal to Persistent Storage.55gamma # [5660982.141833] gamma systemd[1]: Starting Flush Journal to Persistent Storage...56beta # [5660982.267821] beta systemd[1]: Starting Create System Files and Directories...57gamma # [5660982.142892] gamma systemd[1]: Starting Network Name Resolution...58beta # [5660982.284441] beta systemd-tmpfiles[175]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted59gamma # [5660982.143804] gamma systemd[1]: Starting Create Static Device Nodes in /dev...60beta # [5660982.284637] beta systemd-tmpfiles[175]: fchmod() of /var/log/journal failed: Operation not permitted61gamma # [5660982.154041] gamma systemd-journald[86]: Time spent on flushing to /var/log/journal/824121db561f465fbba8d18d2c839bc3 is 1.875ms for 7 entries.62beta # [5660982.284767] beta systemd-tmpfiles[175]: fchmod() of /var/log/journal/4326dd0ba69b45dab5e757d0f1604146 failed: Operation not permitted63gamma # [5660982.154041] gamma systemd-journald[86]: System Journal (/var/log/journal/824121db561f465fbba8d18d2c839bc3) is 8M, max 4G, 3.9G free.64beta # [5660982.284961] beta systemd-tmpfiles[175]: fchmod() of /run/log/journal failed: Operation not permitted65gamma # [5660982.174585] gamma systemd[1]: Finished Create Static Device Nodes in /dev.66beta # [5660982.288260] beta systemd[1]: Finished Create System Files and Directories.67gamma # [5660982.175592] gamma systemd[1]: Reached target Preparation for Local File Systems.68beta # [5660982.290141] beta systemd[1]: Starting Rebuild Journal Catalog...69gamma # [5660982.175757] gamma systemd[1]: Reached target Local File Systems.70alpha # [5660982.110113] alpha systemd-journald[86]: Journal started71beta # [5660982.291070] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...72alpha # [5660982.110167] alpha systemd-journald[86]: Runtime Journal (/run/log/journal/978c2b501bc84ab18ebb1597a32cab2d) is 8M, max 2.5G, 2.4G free.73beta # [5660982.304073] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.74alpha # [5660982.117141] alpha systemd[1]: Finished Apply Kernel Variables.75beta # [5660982.311194] beta systemd[1]: Finished Rebuild Journal Catalog.76gamma # [5660982.177137] gamma systemd[1]: Listening on Boot Loader Control Service Socket.77beta # [5660982.311416] beta systemd[1]: Finished Save Transient machine-id to Disk.78gamma # [5660982.177218] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container79alpha # [5660982.128530] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.80gamma # [5660982.178208] gamma systemd[1]: Starting Save Transient machine-id to Disk...81beta # [5660982.313115] beta systemd[1]: Starting Update is Completed...82gamma # [5660982.178283] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys83beta # [5660982.314583] beta systemd[1]: Finished Firewall.84beta # [5660982.314949] beta systemd[1]: Reached target Preparation for Network.85gamma # [5660982.263917] gamma systemd[1]: Finished Flush Journal to Persistent Storage.86beta # [5660982.315228] beta systemd[1]: Listening on Network Management Resolve Hook Socket.87alpha # [5660982.144997] alpha systemd[1]: Starting Flush Journal to Persistent Storage...88gamma # [5660982.265969] gamma systemd[1]: Starting Create System Files and Directories...89gamma # [5660982.283373] gamma systemd-tmpfiles[179]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted90alpha # [5660982.145946] alpha systemd[1]: Starting Network Name Resolution...91gamma # [5660982.283551] gamma systemd-tmpfiles[179]: fchmod() of /var/log/journal failed: Operation not permitted92gamma # [5660982.283673] gamma systemd-tmpfiles[179]: fchmod() of /var/log/journal/824121db561f465fbba8d18d2c839bc3 failed: Operation not permitted93gamma # [5660982.283859] gamma systemd-tmpfiles[179]: fchmod() of /run/log/journal failed: Operation not permitted94gamma # [5660982.285804] gamma systemd[1]: Finished Create System Files and Directories.95gamma # [5660982.287774] gamma systemd[1]: Starting Rebuild Journal Catalog...96gamma # [5660982.288711] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...97gamma # [5660982.302306] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.98alpha # [5660982.146713] alpha systemd[1]: Starting Create Static Device Nodes in /dev...99alpha # [5660982.155564] alpha systemd-journald[86]: Time spent on flushing to /var/log/journal/978c2b501bc84ab18ebb1597a32cab2d is 1.816ms for 7 entries.100alpha # [5660982.155564] alpha systemd-journald[86]: System Journal (/var/log/journal/978c2b501bc84ab18ebb1597a32cab2d) is 8M, max 4G, 3.9G free.101alpha # [5660982.173582] alpha systemd[1]: Finished Create Static Device Nodes in /dev.102gamma # [5660982.304326] gamma systemd[1]: Finished Firewall.103alpha # [5660982.174529] alpha systemd[1]: Reached target Preparation for Local File Systems.104gamma # [5660982.304484] gamma systemd[1]: Reached target Preparation for Network.105alpha # [5660982.174698] alpha systemd[1]: Reached target Local File Systems.106gamma # [5660982.304765] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.107alpha # [5660982.176122] alpha systemd[1]: Listening on Boot Loader Control Service Socket.108gamma # [5660982.305858] gamma systemd[1]: Starting Network Management...109alpha # [5660982.176212] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container110gamma # [5660982.309730] gamma systemd[1]: Finished Rebuild Journal Catalog.111alpha # [5660982.177394] alpha systemd[1]: Starting Save Transient machine-id to Disk...112gamma # [5660982.310888] gamma systemd[1]: Starting Update is Completed...113alpha # [5660982.177465] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys114gamma # [5660982.311174] gamma systemd[1]: Finished Save Transient machine-id to Disk.115alpha # [5660982.262651] alpha systemd[1]: Finished Flush Journal to Persistent Storage.116gamma # [5660982.323207] gamma systemd[1]: Finished Update is Completed.117alpha # [5660982.265782] alpha systemd[1]: Starting Create System Files and Directories...118beta # [5660982.316509] beta systemd[1]: Starting Network Management...119alpha # [5660982.284017] alpha systemd-tmpfiles[175]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted120beta # [5660982.325159] beta systemd[1]: Finished Update is Completed.121alpha # [5660982.284194] alpha systemd-tmpfiles[175]: fchmod() of /var/log/journal failed: Operation not permitted122gamma # [5660983.100662] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.123beta # [5660983.109534] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.124gamma # [5660983.120307] gamma systemd-networkd[200]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted125beta # [5660983.142793] beta systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted126beta # [5660983.142886] beta systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted127alpha # [5660982.284314] alpha systemd-tmpfiles[175]: fchmod() of /var/log/journal/978c2b501bc84ab18ebb1597a32cab2d failed: Operation not permitted128gamma # [5660983.120780] gamma systemd-networkd[200]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted129alpha # [5660982.284504] alpha systemd-tmpfiles[175]: fchmod() of /run/log/journal failed: Operation not permitted130beta # [5660983.156834] beta 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.131beta # [5660983.157005] beta 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.132gamma # [5660983.131429] gamma systemd-networkd[200]: /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.133alpha # [5660982.286375] alpha systemd[1]: Finished Create System Files and Directories.134gamma # [5660983.131615] gamma systemd-networkd[200]: /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.135gamma # [5660983.141185] gamma systemd-networkd[200]: lo: Link UP136gamma # [5660983.141191] gamma systemd-networkd[200]: lo: Gained carrier137beta # [5660983.157184] beta systemd-networkd[204]: lo: Link UP138beta # [5660983.157188] beta systemd-networkd[204]: lo: Gained carrier139beta # [5660983.157413] beta systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.140beta # [5660983.158351] beta systemd[1]: Started Network Management.141beta # [5660983.173051] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...142beta # [5660983.173189] beta systemd-networkd[204]: eth1: Link UP143beta # [5660983.173491] beta systemd-networkd[204]: eth1: Gained carrier144alpha # [5660982.287985] alpha systemd[1]: Starting Rebuild Journal Catalog...145alpha # [5660982.288783] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...146alpha # [5660982.302341] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.147alpha # [5660982.309764] alpha systemd[1]: Finished Rebuild Journal Catalog.148alpha # [5660982.310043] alpha systemd[1]: Finished Firewall.149alpha # [5660982.310998] alpha systemd[1]: Finished Save Transient machine-id to Disk.150alpha # [5660982.311760] alpha systemd[1]: Reached target Preparation for Network.151alpha # [5660982.312130] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.152alpha # [5660982.313294] alpha systemd[1]: Starting Network Management...153alpha # [5660982.314187] alpha systemd[1]: Starting Update is Completed...154alpha # [5660982.325459] alpha systemd[1]: Finished Update is Completed.155alpha # [5660983.111785] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.156alpha # [5660983.118001] alpha systemd-networkd[202]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted157alpha # [5660983.118094] alpha systemd-networkd[202]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted158alpha # [5660983.126670] alpha systemd-networkd[202]: /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.159alpha # [5660983.126835] alpha systemd-networkd[202]: /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.160alpha # [5660983.126998] alpha systemd-networkd[202]: lo: Link UP161alpha # [5660983.127003] alpha systemd-networkd[202]: lo: Gained carrier162alpha # [5660983.127216] alpha systemd-networkd[202]: eth1: Configuring with /etc/systemd/network/40-eth1.network.163alpha # [5660983.127987] alpha systemd[1]: Started Network Management.164alpha # [5660983.128078] alpha systemd-networkd[202]: eth1: Link UP165alpha # [5660983.128357] alpha systemd-networkd[202]: eth1: Gained carrier166alpha # [5660983.129819] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...167alpha # [5660983.183252] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.168gamma # [5660983.142278] gamma systemd-networkd[200]: eth1: Configuring with /etc/systemd/network/40-eth1.network.169gamma # [5660983.142736] gamma systemd[1]: Started Network Management.170gamma # [5660983.172533] gamma systemd-networkd[200]: eth1: Link UP171gamma # [5660983.172887] gamma systemd-networkd[200]: eth1: Gained carrier172gamma # [5660983.173119] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...173gamma # [5660983.210767] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.174beta # [5660983.230342] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.175alpha # [5660983.457097] alpha systemd-resolved[120]: Positive Trust Anchors:176alpha # [5660983.457109] alpha systemd-resolved[120]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d177alpha # [5660983.457113] alpha systemd-resolved[120]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16178alpha # [5660983.457148] alpha systemd-resolved[120]: 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 test179alpha # [5660983.480501] alpha systemd-resolved[120]: Using system hostname 'alpha'.180alpha # [5660983.482101] alpha systemd[1]: Started Network Name Resolution.181alpha # [5660983.482194] alpha systemd[1]: Reached target Network.182alpha # [5660983.482452] alpha systemd[1]: Reached target System Initialization.183alpha # [5660983.482515] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container184alpha # [5660983.482543] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.185alpha # [5660983.482559] alpha systemd[1]: Reached target Timer Units.186alpha # [5660983.482919] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.187alpha # [5660983.483065] alpha systemd[1]: Listening on Nix Daemon Socket.188alpha # [5660983.483386] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.189alpha # [5660983.483417] alpha systemd[1]: Reached target Socket Units.190alpha # [5660983.483465] alpha systemd[1]: Reached target Basic System.191alpha # [5660983.485060] alpha systemd[1]: Starting data mesher daemon...192alpha # [5660983.485987] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...193alpha # [5660983.487070] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...194alpha # [5660983.488525] alpha systemd[1]: Starting D-Bus System Message Bus...195alpha # [5660983.506850] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.196alpha # [5660983.674510] alpha nsncd[211]: Aug 13 11:53:29.727 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"197alpha # [5660983.674693] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).198alpha # [5660983.674800] alpha systemd[1]: Reached target Host and Network Name Lookups.199alpha # [5660983.674905] alpha systemd[1]: Reached target User and Group Name Lookups.200alpha # [5660983.676892] alpha systemd[1]: Starting User Login Management...201alpha # [5660983.678311] alpha systemd[1]: Starting Permit User Sessions...202alpha # [5660983.689047] alpha systemd[1]: Finished Permit User Sessions.203alpha # [5660983.689964] alpha systemd[1]: Started Console Getty.204alpha # [5660983.690002] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0205alpha # [5660983.690019] alpha systemd[1]: Reached target Login Prompts.206alpha # [5660983.776489] alpha dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...207alpha # [5660983.777697] alpha dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'208alpha # [5660983.777697] alpha dbus-broker-launch[212]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"209beta # [5660983.465565] beta systemd-resolved[120]: Positive Trust Anchors:210beta # [5660983.465578] beta systemd-resolved[120]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d211beta # [5660983.465581] beta systemd-resolved[120]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16212beta # [5660983.465619] beta systemd-resolved[120]: 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 test213beta # [5660983.489358] beta systemd-resolved[120]: Using system hostname 'beta'.214beta # [5660983.490862] beta systemd[1]: Started Network Name Resolution.215beta # [5660983.490957] beta systemd[1]: Reached target Network.216beta # [5660983.491028] beta systemd[1]: Reached target System Initialization.217beta # [5660983.491074] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container218beta # [5660983.491106] beta systemd[1]: Started Daily Cleanup of Temporary Directories.219beta # [5660983.491125] beta systemd[1]: Reached target Timer Units.220beta # [5660983.491257] beta systemd[1]: Listening on D-Bus System Message Bus Socket.221beta # [5660983.491391] beta systemd[1]: Listening on Nix Daemon Socket.222beta # [5660983.491512] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.223beta # [5660983.491533] beta systemd[1]: Reached target Socket Units.224beta # [5660983.491570] beta systemd[1]: Reached target Basic System.225beta # [5660983.492911] beta systemd[1]: Starting data mesher daemon...226beta # [5660983.493692] beta systemd[1]: Starting Import lastlog data into lastlog2 database...227beta # [5660983.494587] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...228beta # [5660983.495926] beta systemd[1]: Starting D-Bus System Message Bus...229beta # [5660983.511315] beta systemd[1]: Finished Import lastlog data into lastlog2 database.230beta # [5660983.641951] beta nsncd[212]: Aug 13 11:53:29.695 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"231beta # [5660983.642040] beta systemd[1]: Started Name Service Cache Daemon (nsncd).232beta # [5660983.642114] beta systemd[1]: Reached target Host and Network Name Lookups.233beta # [5660983.642173] beta systemd[1]: Reached target User and Group Name Lookups.234beta # [5660983.661113] beta systemd[1]: Starting User Login Management...235beta # [5660983.661996] beta systemd[1]: Starting Permit User Sessions...236beta # [5660983.671042] beta systemd[1]: Finished Permit User Sessions.237beta # [5660983.672710] beta systemd[1]: Started Console Getty.238beta # [5660983.672758] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0239beta # [5660983.672775] beta systemd[1]: Reached target Login Prompts.240beta # [5660983.783907] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...241beta # [5660983.784744] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'242beta # [5660983.784744] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"243gamma # [5660983.473954] gamma systemd-resolved[122]: Positive Trust Anchors:244gamma # [5660983.473966] gamma systemd-resolved[122]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d245gamma # [5660983.473969] gamma systemd-resolved[122]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16246gamma # [5660983.474010] gamma systemd-resolved[122]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test247gamma # [5660983.497337] gamma systemd-resolved[122]: Using system hostname 'gamma'.248gamma # [5660983.498777] gamma systemd[1]: Started Network Name Resolution.249gamma # [5660983.498858] gamma systemd[1]: Reached target Network.250gamma # [5660983.498959] gamma systemd[1]: Reached target System Initialization.251gamma # [5660983.499009] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container252gamma # [5660983.499036] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.253gamma # [5660983.499051] gamma systemd[1]: Reached target Timer Units.254gamma # [5660983.499178] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.255gamma # [5660983.499294] gamma systemd[1]: Listening on Nix Daemon Socket.256gamma # [5660983.499405] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.257gamma # [5660983.499429] gamma systemd[1]: Reached target Socket Units.258gamma # [5660983.499467] gamma systemd[1]: Reached target Basic System.259gamma # [5660983.500675] gamma systemd[1]: Starting data mesher daemon...260gamma # [5660983.501423] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...261gamma # [5660983.502160] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...262gamma # [5660983.503780] gamma systemd[1]: Starting D-Bus System Message Bus...263gamma # [5660983.520134] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.264gamma # [5660983.629732] gamma nsncd[211]: Aug 13 11:53:29.682 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"265gamma # [5660983.629826] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).266gamma # [5660983.629896] gamma systemd[1]: Reached target Host and Network Name Lookups.267gamma # [5660983.629952] gamma systemd[1]: Reached target User and Group Name Lookups.268gamma # [5660983.661676] gamma systemd[1]: Starting User Login Management...269gamma # [5660983.663018] gamma systemd[1]: Starting Permit User Sessions...270gamma # [5660983.674988] gamma systemd[1]: Finished Permit User Sessions.271gamma # [5660983.675991] gamma systemd[1]: Started Console Getty.272gamma # [5660983.676040] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0273gamma # [5660983.676057] gamma systemd[1]: Reached target Login Prompts.274beta # [5660983.785298] beta systemd[1]: Started D-Bus System Message Bus.275beta # [5660983.792575] beta dbus-broker-launch[213]: Ready276gamma # [5660983.791123] gamma dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...277gamma # [5660983.791996] gamma dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'278gamma # [5660983.791996] gamma dbus-broker-launch[212]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"279gamma # [5660983.792466] gamma systemd[1]: Started D-Bus System Message Bus.280gamma # [5660983.799976] gamma dbus-broker-launch[212]: Ready281alpha # [5660983.778201] alpha systemd[1]: Started D-Bus System Message Bus.282alpha # [5660983.785924] alpha dbus-broker-launch[212]: Ready283beta # [5660984.171654] beta data-mesher[210]: time=2026-08-13T11:53:30.224Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]284beta # [5660984.172786] beta data-mesher[210]: time=2026-08-13T11:53:30.225Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7: [/dns/alpha.clan/tcp/7946]} {12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj285beta # [5660984.172831] beta data-mesher[210]: time=2026-08-13T11:53:30.225Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml286beta # [5660984.177854] beta data-mesher[210]: time=2026-08-13T11:53:30.231Z level=INFO msg="checking file integrity"287beta # [5660984.177964] beta data-mesher[210]: time=2026-08-13T11:53:30.231Z level=INFO msg="file integrity check complete"288beta # [5660984.182047] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="libp2p host created" peer_id=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj 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]"289beta # [5660984.182086] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="registered HTTP route" method=GET path=/files290beta # [5660984.182086] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name291beta # [5660984.182086] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name292beta # [5660984.182144] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="starting server"293beta # [5660984.182357] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="waiting for DHT to populate" delay=10s294beta # [5660984.182357] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="HTTP server listening" address=[::1]:7331295beta # [5660984.182357] beta data-mesher[210]: time=2026-08-13T11:53:30.235Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331296beta # [5660984.187693] beta data-mesher[210]: time=2026-08-13T11:53:30.240Z level=INFO msg="peer connected" peer_id=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR remote_addr=/ip4/192.168.1.3/tcp/7946297beta # [5660984.240571] beta data-mesher[210]: time=2026-08-13T11:53:30.293Z level=INFO msg="peer connected" peer_id=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 remote_addr=/ip4/192.168.1.1/tcp/7946298beta # [5660984.356214] beta systemd-networkd[204]: eth1: Gained IPv6LL299beta # [5660984.361563] beta systemd-logind[229]: New seat seat0.300beta # [5660984.361773] beta systemd[1]: Started User Login Management.301beta # [5660984.385403] beta systemd[1]: Starting linger-users.service...302beta # [5660984.400167] beta systemd[1]: linger-users.service: Deactivated successfully.303beta # [5660984.400314] beta systemd[1]: Finished linger-users.service.304gamma # [5660984.157780] gamma data-mesher[209]: time=2026-08-13T11:53:30.210Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]305gamma # [5660984.158895] gamma data-mesher[209]: time=2026-08-13T11:53:30.212Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7: [/dns/alpha.clan/tcp/7946]} {12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR306gamma # [5660984.158895] gamma data-mesher[209]: time=2026-08-13T11:53:30.212Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml307gamma # [5660984.177885] gamma data-mesher[209]: time=2026-08-13T11:53:30.231Z level=INFO msg="checking file integrity"308gamma # [5660984.178002] gamma data-mesher[209]: time=2026-08-13T11:53:30.231Z level=INFO msg="file integrity check complete"309gamma # [5660984.182196] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="libp2p host created" peer_id=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR 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]"310gamma # [5660984.182196] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="registered HTTP route" method=GET path=/files311gamma # [5660984.182196] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name312gamma # [5660984.182196] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name313gamma # [5660984.182196] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="starting server"314gamma # [5660984.182196] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="waiting for DHT to populate" delay=10s315gamma # [5660984.182357] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="HTTP server listening" address=[::1]:7331316gamma # [5660984.182357] gamma data-mesher[209]: time=2026-08-13T11:53:30.235Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331317gamma # [5660984.186774] gamma data-mesher[209]: time=2026-08-13T11:53:30.239Z level=INFO msg="peer connected" peer_id=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj remote_addr=/ip4/192.168.1.2/tcp/7946318gamma # [5660984.245978] gamma data-mesher[209]: time=2026-08-13T11:53:30.299Z level=INFO msg="peer connected" peer_id=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 remote_addr=/ip4/192.168.1.1/tcp/7946319gamma # [5660984.351364] gamma systemd-logind[228]: New seat seat0.320gamma # [5660984.351544] gamma systemd[1]: Started User Login Management.321gamma # [5660984.385513] gamma systemd[1]: Starting linger-users.service...322gamma # [5660984.397345] gamma systemd[1]: linger-users.service: Deactivated successfully.323gamma # [5660984.397521] gamma systemd[1]: Finished linger-users.service.324alpha # [5660984.228055] alpha data-mesher[209]: time=2026-08-13T11:53:30.281Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]325alpha # [5660984.229149] alpha data-mesher[209]: time=2026-08-13T11:53:30.282Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7: [/dns/alpha.clan/tcp/7946]} {12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7326alpha # [5660984.229192] alpha data-mesher[209]: time=2026-08-13T11:53:30.282Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml327alpha # [5660984.230658] alpha data-mesher[209]: time=2026-08-13T11:53:30.283Z level=INFO msg="checking file integrity"328alpha # [5660984.230780] alpha data-mesher[209]: time=2026-08-13T11:53:30.283Z level=INFO msg="file integrity check complete"329alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="libp2p host created" peer_id=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 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]"330alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="registered HTTP route" method=GET path=/files331alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name332alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name333alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="starting server"334alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="waiting for DHT to populate" delay=10s335alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="HTTP server listening" address=[::1]:7331336alpha # [5660984.235497] alpha data-mesher[209]: time=2026-08-13T11:53:30.288Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331337alpha # [5660984.239993] alpha data-mesher[209]: time=2026-08-13T11:53:30.293Z level=INFO msg="peer connected" peer_id=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj remote_addr=/ip4/192.168.1.2/tcp/7946338alpha # [5660984.245360] alpha data-mesher[209]: time=2026-08-13T11:53:30.298Z level=INFO msg="peer connected" peer_id=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR remote_addr=/ip4/192.168.1.3/tcp/7946339alpha # [5660984.256186] alpha systemd-networkd[202]: eth1: Gained IPv6LL340alpha # [5660984.373153] alpha systemd-logind[228]: New seat seat0.341alpha # [5660984.373316] alpha systemd[1]: Started User Login Management.342alpha # [5660984.385231] alpha systemd[1]: Starting linger-users.service...343alpha # [5660984.402286] alpha systemd[1]: linger-users.service: Deactivated successfully.344alpha # [5660984.402422] alpha systemd[1]: Finished linger-users.service.345gamma # [5660985.024177] gamma systemd-networkd[200]: eth1: Gained IPv6LL346alpha: still waiting for container 'alpha' to reach ready state...347alpha: (finished: waiting for unit data-mesher.service, in 13.18 seconds)348beta: waiting for unit data-mesher.service349beta: (finished: waiting for unit data-mesher.service, in 0.01 seconds)350gamma: waiting for unit data-mesher.service351gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)352alpha: must succeed: echo -n 'hello world' > /tmp/test_file353alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)354alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key355beta # [5660994.182635] beta data-mesher[210]: time=2026-08-13T11:53:40.235Z level=INFO msg="performing state exchange with peers on join" count=1356beta # [5660994.182635] beta data-mesher[210]: time=2026-08-13T11:53:40.235Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR timeout=5s357beta # [5660994.183138] beta data-mesher[210]: time=2026-08-13T11:53:40.236Z level=INFO msg="received state sync from peer" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR358beta # [5660994.183138] beta data-mesher[210]: time=2026-08-13T11:53:40.236Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR359beta # [5660994.183374] beta data-mesher[210]: time=2026-08-13T11:53:40.236Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR360beta # [5660994.183374] beta data-mesher[210]: time=2026-08-13T11:53:40.236Z level=INFO msg="state exchange complete" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR timeout=5s361beta # [5660994.183463] beta data-mesher[210]: time=2026-08-13T11:53:40.236Z level=INFO msg="server started"362beta # [5660994.183614] beta data-mesher[210]: time=2026-08-13T11:53:40.236Z level=INFO msg="starting expired-file sweeper" interval=1m0s363beta # [5660994.183677] beta systemd[1]: Started data mesher daemon.364beta # [5660994.183989] beta systemd[1]: Reached target Multi-User System.365beta # [5660994.184261] beta systemd[1]: Startup finished in 12.467s.366beta # [5660994.236179] beta data-mesher[210]: time=2026-08-13T11:53:40.289Z level=INFO msg="received state sync from peer" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7367beta # [5660994.236179] beta data-mesher[210]: time=2026-08-13T11:53:40.289Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7368gamma # [5660994.182435] gamma data-mesher[209]: time=2026-08-13T11:53:40.235Z level=INFO msg="performing state exchange with peers on join" count=1369gamma # [5660994.182435] gamma data-mesher[209]: time=2026-08-13T11:53:40.235Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s370gamma # [5660994.183229] gamma data-mesher[209]: time=2026-08-13T11:53:40.236Z level=INFO msg="received state sync from peer" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj371gamma # [5660994.183229] gamma data-mesher[209]: time=2026-08-13T11:53:40.236Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj372gamma # [5660994.183229] gamma data-mesher[209]: time=2026-08-13T11:53:40.236Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj373gamma # [5660994.183229] gamma data-mesher[209]: time=2026-08-13T11:53:40.236Z level=INFO msg="state exchange complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s374gamma # [5660994.183229] gamma data-mesher[209]: time=2026-08-13T11:53:40.236Z level=INFO msg="server started"375gamma # [5660994.183476] gamma data-mesher[209]: time=2026-08-13T11:53:40.236Z level=INFO msg="starting expired-file sweeper" interval=1m0s376gamma # [5660994.183523] gamma systemd[1]: Started data mesher daemon.377gamma # [5660994.184078] gamma systemd[1]: Reached target Multi-User System.378gamma # [5660994.184408] gamma systemd[1]: Startup finished in 12.454s.379alpha # [5660994.235586] alpha data-mesher[209]: time=2026-08-13T11:53:40.288Z level=INFO msg="performing state exchange with peers on join" count=1380alpha # [5660994.235586] alpha data-mesher[209]: time=2026-08-13T11:53:40.288Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s381alpha # [5660994.236446] alpha data-mesher[209]: time=2026-08-13T11:53:40.289Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj382alpha # [5660994.236446] alpha data-mesher[209]: time=2026-08-13T11:53:40.289Z level=INFO msg="state exchange complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s383alpha # [5660994.236563] alpha data-mesher[209]: time=2026-08-13T11:53:40.289Z level=INFO msg="server started"384alpha # [5660994.236662] alpha data-mesher[209]: time=2026-08-13T11:53:40.289Z level=INFO msg="starting expired-file sweeper" interval=1m0s385alpha # [5660994.236753] alpha systemd[1]: Started data mesher daemon.386alpha # [5660994.236977] alpha systemd[1]: Reached target Multi-User System.387alpha # [5660994.237138] alpha systemd[1]: Startup finished in 12.502s.388alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.24 seconds)389??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.390 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39391alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file392??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.393 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39394alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)395beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file396alpha # [5660994.649845] alpha data-mesher[209]: time=2026-08-13T11:53:40.702Z level=INFO msg=http_request uri=/files/test_file status=204397beta # [5660999.184213] beta data-mesher[210]: time=2026-08-13T11:53:45.237Z level=DEBUG msg="attempting push/pull" peer_count=2398beta # [5660999.185711] beta data-mesher[210]: time=2026-08-13T11:53:45.237Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s399beta # [5660999.186233] beta data-mesher[210]: time=2026-08-13T11:53:45.239Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7400beta # [5660999.186233] beta data-mesher[210]: time=2026-08-13T11:53:45.239Z level=DEBUG msg="new file detected" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 name=test_file name=test_file401beta # [5660999.186233] beta data-mesher[210]: time=2026-08-13T11:53:45.239Z level=INFO msg="state exchange complete" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s402beta # [5660999.186233] beta data-mesher[210]: time=2026-08-13T11:53:45.239Z level=DEBUG msg="push/pull successful" interval=5s403beta # [5660999.186233] beta data-mesher[210]: time=2026-08-13T11:53:45.239Z level=INFO msg="scheduling file download" name=test_file404beta # [5660999.186233] beta data-mesher[210]: time=2026-08-13T11:53:45.239Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-13 11:53:40.5 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7405beta # [5660999.237876] beta data-mesher[210]: time=2026-08-13T11:53:45.290Z level=INFO msg="received state sync from peer" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7406beta # [5660999.237876] beta data-mesher[210]: time=2026-08-13T11:53:45.290Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7407beta # [5660999.237876] beta data-mesher[210]: time=2026-08-13T11:53:45.290Z level=DEBUG msg="new file detected" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 name=test_file name=test_file408beta # [5660999.273512] beta data-mesher[210]: time=2026-08-13T11:53:45.326Z level=INFO msg="download complete" name=test_file signed_at="2026-08-13 11:53:40.5 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 written=true elapsed=87.276507ms409gamma # [5660999.184216] gamma data-mesher[209]: time=2026-08-13T11:53:45.237Z level=DEBUG msg="attempting push/pull" peer_count=2410gamma # [5660999.185363] gamma data-mesher[209]: time=2026-08-13T11:53:45.237Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s411gamma # [5660999.185943] gamma data-mesher[209]: time=2026-08-13T11:53:45.239Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7412gamma # [5660999.185943] gamma data-mesher[209]: time=2026-08-13T11:53:45.239Z level=DEBUG msg="new file detected" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 name=test_file name=test_file413gamma # [5660999.186075] gamma data-mesher[209]: time=2026-08-13T11:53:45.239Z level=INFO msg="state exchange complete" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s414gamma # [5660999.186075] gamma data-mesher[209]: time=2026-08-13T11:53:45.239Z level=DEBUG msg="push/pull successful" interval=5s415gamma # [5660999.186075] gamma data-mesher[209]: time=2026-08-13T11:53:45.239Z level=INFO msg="scheduling file download" name=test_file416gamma # [5660999.186075] gamma data-mesher[209]: time=2026-08-13T11:53:45.239Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-13 11:53:40.5 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7417gamma # [5660999.265209] gamma data-mesher[209]: time=2026-08-13T11:53:45.318Z level=INFO msg="download complete" name=test_file signed_at="2026-08-13 11:53:40.5 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 written=true elapsed=79.119072ms418alpha # [5660999.185292] alpha data-mesher[209]: time=2026-08-13T11:53:45.238Z level=INFO msg="received state sync from peer" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR419alpha # [5660999.185292] alpha data-mesher[209]: time=2026-08-13T11:53:45.238Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR420alpha # [5660999.185784] alpha data-mesher[209]: time=2026-08-13T11:53:45.238Z level=INFO msg="received state sync from peer" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj421alpha # [5660999.185784] alpha data-mesher[209]: time=2026-08-13T11:53:45.238Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj422alpha # [5660999.186566] alpha data-mesher[209]: time=2026-08-13T11:53:45.239Z level=INFO msg="received file request" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_file423alpha # [5660999.186760] alpha data-mesher[209]: time=2026-08-13T11:53:45.239Z level=INFO msg="received file request" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_file424alpha # [5660999.189290] alpha data-mesher[209]: time=2026-08-13T11:53:45.242Z level=INFO msg="file transfer complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_file425alpha # [5660999.189860] alpha data-mesher[209]: time=2026-08-13T11:53:45.243Z level=INFO msg="file transfer complete" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_file426alpha # [5660999.237036] alpha data-mesher[209]: time=2026-08-13T11:53:45.290Z level=DEBUG msg="attempting push/pull" peer_count=2427alpha # [5660999.237183] alpha data-mesher[209]: time=2026-08-13T11:53:45.290Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s428alpha # [5660999.237985] alpha data-mesher[209]: time=2026-08-13T11:53:45.291Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj429alpha # [5660999.237985] alpha data-mesher[209]: time=2026-08-13T11:53:45.291Z level=INFO msg="state exchange complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s430alpha # [5660999.238208] alpha data-mesher[209]: time=2026-08-13T11:53:45.291Z level=DEBUG msg="push/pull successful" interval=5s431beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.05 seconds)432gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file433gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)434beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key435beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.05 seconds)436alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file437beta # [5660999.774207] beta data-mesher[210]: time=2026-08-13T11:53:45.827Z level=INFO msg=http_request uri=/files/test_file status=204438beta # [5661004.186939] beta data-mesher[210]: time=2026-08-13T11:53:50.240Z level=DEBUG msg="attempting push/pull" peer_count=2439beta # [5661004.187700] beta data-mesher[210]: time=2026-08-13T11:53:50.240Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR timeout=5s440beta # [5661004.239121] beta data-mesher[210]: time=2026-08-13T11:53:50.292Z level=INFO msg="received state sync from peer" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7441beta # [5661004.239121] beta data-mesher[210]: time=2026-08-13T11:53:50.292Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7442beta # [5661004.325266] beta data-mesher[210]: time=2026-08-13T11:53:50.378Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR443beta # [5661004.325400] beta data-mesher[210]: time=2026-08-13T11:53:50.378Z level=INFO msg="state exchange complete" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR timeout=5s444beta # [5661004.325400] beta data-mesher[210]: time=2026-08-13T11:53:50.378Z level=DEBUG msg="push/pull successful" interval=5s445gamma # [5661004.186500] gamma data-mesher[209]: time=2026-08-13T11:53:50.239Z level=DEBUG msg="attempting push/pull" peer_count=2446gamma # [5661004.187243] gamma data-mesher[209]: time=2026-08-13T11:53:50.239Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s447gamma # [5661004.187578] gamma data-mesher[209]: time=2026-08-13T11:53:50.240Z level=INFO msg="received state sync from peer" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj448gamma # [5661004.187578] gamma data-mesher[209]: time=2026-08-13T11:53:50.240Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj449gamma # [5661004.187578] gamma data-mesher[209]: time=2026-08-13T11:53:50.240Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7450gamma # [5661004.187778] gamma data-mesher[209]: time=2026-08-13T11:53:50.240Z level=INFO msg="state exchange complete" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s451gamma # [5661004.187778] gamma data-mesher[209]: time=2026-08-13T11:53:50.240Z level=DEBUG msg="push/pull successful" interval=5s452gamma # [5661004.324831] gamma data-mesher[209]: time=2026-08-13T11:53:50.377Z level=DEBUG msg="imported tombstone" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj name=test_file written=true453alpha # [5661004.187205] alpha data-mesher[209]: time=2026-08-13T11:53:50.240Z level=INFO msg="received state sync from peer" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR454alpha # [5661004.187205] alpha data-mesher[209]: time=2026-08-13T11:53:50.240Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR455alpha # [5661004.238517] alpha data-mesher[209]: time=2026-08-13T11:53:50.291Z level=DEBUG msg="attempting push/pull" peer_count=2456alpha # [5661004.238684] alpha data-mesher[209]: time=2026-08-13T11:53:50.291Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s457alpha # [5661004.239532] alpha data-mesher[209]: time=2026-08-13T11:53:50.292Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj458alpha # [5661004.325397] alpha data-mesher[209]: time=2026-08-13T11:53:50.378Z level=DEBUG msg="imported tombstone" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj name=test_file written=true459alpha # [5661004.325397] alpha data-mesher[209]: time=2026-08-13T11:53:50.378Z level=INFO msg="state exchange complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s460alpha # [5661004.325587] alpha data-mesher[209]: time=2026-08-13T11:53:50.378Z level=DEBUG msg="push/pull successful" interval=5s461alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.05 seconds)462beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file463beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)464gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file465gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)466alpha: must succeed: cat /nix/store/5gqynv34nxphpzmpylg87hy8qlc8g0xs-per-machine-alpha-data-mesher-node-identity_identity.pub467alpha: (finished: must succeed: cat /nix/store/5gqynv34nxphpzmpylg87hy8qlc8g0xs-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)468alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file469alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)470alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert471alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)472alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4473alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4, in 0.01 seconds)474beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4475alpha # [5661004.889411] alpha data-mesher[209]: time=2026-08-13T11:53:50.942Z level=INFO msg=http_request uri=/files/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 status=204476beta # [5661009.190041] beta data-mesher[210]: time=2026-08-13T11:53:55.243Z level=INFO msg="received state sync from peer" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR477beta # [5661009.190041] beta data-mesher[210]: time=2026-08-13T11:53:55.243Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR478beta # [5661009.325531] beta data-mesher[210]: time=2026-08-13T11:53:55.378Z level=DEBUG msg="attempting push/pull" peer_count=2479beta # [5661009.325531] beta data-mesher[210]: time=2026-08-13T11:53:55.378Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s480beta # [5661009.327588] beta data-mesher[210]: time=2026-08-13T11:53:55.380Z level=INFO msg="received state sync from peer" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7481beta # [5661009.327588] beta data-mesher[210]: time=2026-08-13T11:53:55.380Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7482beta # [5661009.327692] beta data-mesher[210]: time=2026-08-13T11:53:55.380Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7483beta # [5661009.328169] beta data-mesher[210]: time=2026-08-13T11:53:55.381Z level=DEBUG msg="new file detected" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 name=test_file name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4484beta # [5661009.328303] beta data-mesher[210]: time=2026-08-13T11:53:55.381Z level=INFO msg="scheduling file download" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4485beta # [5661009.328363] beta data-mesher[210]: time=2026-08-13T11:53:55.381Z level=DEBUG msg="new file detected" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 name=test_file name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4486beta # [5661009.328363] beta data-mesher[210]: time=2026-08-13T11:53:55.381Z level=INFO msg="state exchange complete" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s487beta # [5661009.328405] beta data-mesher[210]: time=2026-08-13T11:53:55.381Z level=INFO msg="downloading file" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 signed_at="2026-08-13 11:53:50.935 +0000 UTC" signed_by="KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7488beta # [5661009.328405] beta data-mesher[210]: time=2026-08-13T11:53:55.381Z level=DEBUG msg="push/pull successful" interval=5s489beta # [5661009.331328] beta data-mesher[210]: time=2026-08-13T11:53:55.384Z level=INFO msg="download complete" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 signed_at="2026-08-13 11:53:50.935 +0000 UTC" signed_by="KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 written=true elapsed=2.983922ms490gamma # [5661009.188675] gamma data-mesher[209]: time=2026-08-13T11:53:55.241Z level=DEBUG msg="attempting push/pull" peer_count=2491gamma # [5661009.188675] gamma data-mesher[209]: time=2026-08-13T11:53:55.241Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s492gamma # [5661009.191002] gamma data-mesher[209]: time=2026-08-13T11:53:55.244Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj493gamma # [5661009.191119] gamma data-mesher[209]: time=2026-08-13T11:53:55.244Z level=INFO msg="state exchange complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s494gamma # [5661009.191119] gamma data-mesher[209]: time=2026-08-13T11:53:55.244Z level=DEBUG msg="push/pull successful" interval=5s495alpha # [5661009.325967] alpha data-mesher[209]: time=2026-08-13T11:53:55.379Z level=DEBUG msg="attempting push/pull" peer_count=2496alpha # [5661009.326730] alpha data-mesher[209]: time=2026-08-13T11:53:55.379Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s497alpha # [5661009.326730] alpha data-mesher[209]: time=2026-08-13T11:53:55.379Z level=INFO msg="received state sync from peer" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj498alpha # [5661009.326730] alpha data-mesher[209]: time=2026-08-13T11:53:55.379Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj499alpha # [5661009.328421] alpha data-mesher[209]: time=2026-08-13T11:53:55.381Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj500alpha # [5661009.328421] alpha data-mesher[209]: time=2026-08-13T11:53:55.381Z level=INFO msg="state exchange complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj timeout=5s501alpha # [5661009.328560] alpha data-mesher[209]: time=2026-08-13T11:53:55.381Z level=DEBUG msg="push/pull successful" interval=5s502alpha # [5661009.328709] alpha data-mesher[209]: time=2026-08-13T11:53:55.381Z level=INFO msg="received file request" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4503alpha # [5661009.329883] alpha data-mesher[209]: time=2026-08-13T11:53:55.383Z level=INFO msg="file transfer complete" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4504beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4, in 5.05 seconds)505gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4506gamma # [5661014.191191] gamma data-mesher[209]: time=2026-08-13T11:54:00.244Z level=DEBUG msg="attempting push/pull" peer_count=2507gamma # [5661014.192160] gamma data-mesher[209]: time=2026-08-13T11:54:00.244Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s508gamma # [5661014.192370] gamma data-mesher[209]: time=2026-08-13T11:54:00.245Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7509gamma # [5661014.192997] gamma data-mesher[209]: time=2026-08-13T11:54:00.246Z level=DEBUG msg="new file detected" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 name=test_file name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4510gamma # [5661014.193066] gamma data-mesher[209]: time=2026-08-13T11:54:00.246Z level=INFO msg="state exchange complete" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s511gamma # [5661014.193066] gamma data-mesher[209]: time=2026-08-13T11:54:00.246Z level=DEBUG msg="push/pull successful" interval=5s512gamma # [5661014.193172] gamma data-mesher[209]: time=2026-08-13T11:54:00.246Z level=INFO msg="scheduling file download" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4513gamma # [5661014.193229] gamma data-mesher[209]: time=2026-08-13T11:54:00.246Z level=INFO msg="downloading file" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 signed_at="2026-08-13 11:53:50.935 +0000 UTC" signed_by="KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7514gamma # [5661014.196097] gamma data-mesher[209]: time=2026-08-13T11:54:00.249Z level=INFO msg="download complete" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 signed_at="2026-08-13 11:53:50.935 +0000 UTC" signed_by="KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4=" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 written=true elapsed=2.904201ms515gamma # [5661014.329501] gamma data-mesher[209]: time=2026-08-13T11:54:00.382Z level=INFO msg="received state sync from peer" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7516gamma # [5661014.329501] gamma data-mesher[209]: time=2026-08-13T11:54:00.382Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7517alpha # [5661014.191714] alpha data-mesher[209]: time=2026-08-13T11:54:00.244Z level=INFO msg="received state sync from peer" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR518alpha # [5661014.191714] alpha data-mesher[209]: time=2026-08-13T11:54:00.244Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR519alpha # [5661014.193583] alpha data-mesher[209]: time=2026-08-13T11:54:00.246Z level=INFO msg="received file request" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4520alpha # [5661014.194389] alpha data-mesher[209]: time=2026-08-13T11:54:00.247Z level=INFO msg="file transfer complete" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR network="fa3mIMgTG0/0AecafYJw5m2QNqXbIsV0qF7wWMeWxgE=" name=test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4521alpha # [5661014.328875] alpha data-mesher[209]: time=2026-08-13T11:54:00.381Z level=DEBUG msg="attempting push/pull" peer_count=2522alpha # [5661014.329074] alpha data-mesher[209]: time=2026-08-13T11:54:00.382Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR timeout=5s523alpha # [5661014.329984] alpha data-mesher[209]: time=2026-08-13T11:54:00.383Z level=INFO msg="received state sync from peer" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj524alpha # [5661014.329984] alpha data-mesher[209]: time=2026-08-13T11:54:00.383Z level=INFO msg="merging remote state" peer=12D3KooWFAXEMbTXyNPyer13C5roA18AwFuWxCjYhQs4FKgUWdMj525alpha # [5661014.330474] alpha data-mesher[209]: time=2026-08-13T11:54:00.383Z level=INFO msg="merging remote state" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR526alpha # [5661014.331080] alpha data-mesher[209]: time=2026-08-13T11:54:00.384Z level=INFO msg="state exchange complete" peer=12D3KooWHf6L5PzEjqa9BYD2fNvWMMbXjRWTE4LrN3f3XzCj55PR timeout=5s527alpha # [5661014.331147] alpha data-mesher[209]: time=2026-08-13T11:54:00.384Z level=DEBUG msg="push/pull successful" interval=5s528beta # [5661014.329471] beta data-mesher[210]: time=2026-08-13T11:54:00.382Z level=DEBUG msg="attempting push/pull" peer_count=2529beta # [5661014.330087] beta data-mesher[210]: time=2026-08-13T11:54:00.382Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s530beta # [5661014.330602] beta data-mesher[210]: time=2026-08-13T11:54:00.383Z level=INFO msg="merging remote state" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7531beta # [5661014.331181] beta data-mesher[210]: time=2026-08-13T11:54:00.384Z level=INFO msg="state exchange complete" peer=12D3KooWCXNR4tRYgxzSZhznDmWoZmiGiy3oDA8Mmgn14E6xmVm7 timeout=5s532beta # [5661014.331264] beta data-mesher[210]: time=2026-08-13T11:54:00.384Z level=DEBUG msg="push/pull successful" interval=5s533gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4, in 5.05 seconds)534alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert535alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)536alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub537Error: failed to update file: 403 Forbidden, signer KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4= is not authorized for this file test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4538alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/5p21apv549rk5js9g7fw86gngb2wjh8c-shared-data-mesher-network_network.pub, in 0.03 seconds)539(finished: run the VM test script, in 33.84 seconds)540alpha # [5661015.037804] alpha data-mesher[209]: time=2026-08-13T11:54:01.090Z level=INFO msg=http_request uri=/files/test_ns/KDh3XKXmYAVdtFZ6ZSTXMQseOhgcvO30OunONHi60h4 status=403541test script finished in 36.41s542cleanup543kill NspawnMachine (pid 53)544kill NspawnMachine (pid 54)545kill NspawnMachine (pid 55)546Container alpha terminated by signal KILL.547Container beta terminated by signal KILL.548(finished: cleanup, in 0.39 seconds)549Container gamma terminated by signal KILL.