container-test-run-data-mesher
checks.aarch64-linux.data-mesher
· build #692
· 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(gamma): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.22nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE23nixos-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.24nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE25nixos-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.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 beta on /build/vm-state-beta.30░ Spawning container gamma on /build/vm-state-gamma.31░ Spawning container alpha on /build/vm-state-alpha.32alpha # No journal files were found.33alpha # No journal boot entry found for the specified boot (+0).34beta # No journal files were found.35beta # No journal boot entry found for the specified boot (+0).36gamma # No journal files were found.37gamma # No journal boot entry found for the specified boot (+0).38alpha # [1201574.369513] alpha systemd-journald[91]: Journal started39alpha # [1201574.369572] alpha systemd-journald[91]: Runtime Journal (/run/log/journal/a2e8c2f865234822bc42b9a480a6b6e8) is 8M, max 2.5G, 2.4G free.40alpha # [1201574.372270] alpha systemd[1]: Finished Apply Kernel Variables.41alpha # [1201574.385138] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.42alpha # [1201574.399705] alpha systemd[1]: Starting Flush Journal to Persistent Storage...43alpha # [1201574.400627] alpha systemd[1]: Starting Network Name Resolution...44alpha # [1201574.401376] alpha systemd[1]: Starting Create Static Device Nodes in /dev...45alpha # [1201574.411178] alpha systemd-journald[91]: Time spent on flushing to /var/log/journal/a2e8c2f865234822bc42b9a480a6b6e8 is 1.464ms for 7 entries.46alpha # [1201574.411178] alpha systemd-journald[91]: System Journal (/var/log/journal/a2e8c2f865234822bc42b9a480a6b6e8) is 8M, max 4G, 3.9G free.47alpha # [1201574.427943] alpha systemd[1]: Finished Create Static Device Nodes in /dev.48alpha # [1201574.428774] alpha systemd[1]: Reached target Preparation for Local File Systems.49alpha # [1201574.428926] alpha systemd[1]: Reached target Local File Systems.50alpha # [1201574.429823] alpha systemd[1]: Listening on Boot Loader Control Service Socket.51alpha # [1201574.429883] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container52alpha # [1201574.431163] alpha systemd[1]: Starting Save Transient machine-id to Disk...53alpha # [1201574.431203] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys54alpha # [1201574.434831] alpha systemd[1]: Finished Flush Journal to Persistent Storage.55alpha # [1201574.435804] alpha systemd[1]: Starting Create System Files and Directories...56alpha # [1201574.451508] alpha systemd-tmpfiles[148]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted57alpha # [1201574.451684] alpha systemd-tmpfiles[148]: fchmod() of /var/log/journal failed: Operation not permitted58alpha # [1201574.451807] alpha systemd-tmpfiles[148]: fchmod() of /var/log/journal/a2e8c2f865234822bc42b9a480a6b6e8 failed: Operation not permitted59alpha # [1201574.451989] alpha systemd-tmpfiles[148]: fchmod() of /run/log/journal failed: Operation not permitted60alpha # [1201574.453712] alpha systemd[1]: Finished Create System Files and Directories.61alpha # [1201574.454859] alpha systemd[1]: Starting Rebuild Journal Catalog...62alpha # [1201574.455553] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...63alpha # [1201574.467033] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.64alpha # [1201574.474277] alpha systemd[1]: Finished Rebuild Journal Catalog.65alpha # [1201574.475369] alpha systemd[1]: Starting Update is Completed...66alpha # [1201574.485499] alpha systemd[1]: Finished Update is Completed.67alpha # [1201574.536504] alpha systemd[1]: Finished Firewall.68alpha # [1201574.536668] alpha systemd[1]: Reached target Preparation for Network.69alpha # [1201574.536898] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.70alpha # [1201574.538106] alpha systemd[1]: Starting Network Management...71alpha # [1201574.913016] alpha systemd[1]: Finished Save Transient machine-id to Disk.72alpha # [1201575.291701] alpha systemd-networkd[208]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted73beta # [1201574.376882] beta systemd-journald[90]: Journal started74beta # [1201574.376933] beta systemd-journald[90]: Runtime Journal (/run/log/journal/b4fc14d195534217b2b4933a75952f37) is 8M, max 2.5G, 2.4G free.75alpha # [1201575.291794] alpha systemd-networkd[208]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted76alpha # [1201575.301284] alpha systemd-networkd[208]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.77alpha # [1201575.301453] alpha systemd-networkd[208]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.78alpha # [1201575.301615] alpha systemd-networkd[208]: lo: Link UP79alpha # [1201575.301619] alpha systemd-networkd[208]: lo: Gained carrier80alpha # [1201575.301820] alpha systemd-networkd[208]: eth1: Configuring with /etc/systemd/network/40-eth1.network.81beta # [1201574.385622] beta systemd[1]: Finished Apply Kernel Variables.82alpha # [1201575.302198] alpha systemd[1]: Started Network Management.83beta # [1201574.397537] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.84alpha # [1201575.336394] alpha systemd-networkd[208]: eth1: Link UP85beta # [1201574.412045] beta systemd[1]: Starting Flush Journal to Persistent Storage...86alpha # [1201575.336608] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...87beta # [1201574.412923] beta systemd[1]: Starting Network Name Resolution...88alpha # [1201575.336749] alpha systemd-networkd[208]: eth1: Gained carrier89beta # [1201574.413580] beta systemd[1]: Starting Create Static Device Nodes in /dev...90alpha # [1201575.363743] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.91beta # [1201574.421661] beta systemd-journald[90]: Time spent on flushing to /var/log/journal/b4fc14d195534217b2b4933a75952f37 is 1.386ms for 7 entries.92alpha # [1201575.383346] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.93beta # [1201574.421661] beta systemd-journald[90]: System Journal (/var/log/journal/b4fc14d195534217b2b4933a75952f37) is 8M, max 4G, 3.9G free.94beta # [1201574.441729] beta systemd[1]: Finished Flush Journal to Persistent Storage.95beta # [1201574.442267] beta systemd[1]: Finished Create Static Device Nodes in /dev.96beta # [1201574.443534] beta systemd[1]: Reached target Preparation for Local File Systems.97beta # [1201574.443641] beta systemd[1]: Reached target Local File Systems.98beta # [1201574.444547] beta systemd[1]: Listening on Boot Loader Control Service Socket.99beta # [1201574.444596] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container100beta # [1201574.445452] beta systemd[1]: Starting Save Transient machine-id to Disk...101beta # [1201574.446137] beta systemd[1]: Starting Create System Files and Directories...102beta # [1201574.446174] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys103beta # [1201574.461152] beta systemd-tmpfiles[148]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted104beta # [1201574.461342] beta systemd-tmpfiles[148]: fchmod() of /var/log/journal failed: Operation not permitted105beta # [1201574.461473] beta systemd-tmpfiles[148]: fchmod() of /var/log/journal/b4fc14d195534217b2b4933a75952f37 failed: Operation not permitted106beta # [1201574.461672] beta systemd-tmpfiles[148]: fchmod() of /run/log/journal failed: Operation not permitted107beta # [1201574.463262] beta systemd[1]: Finished Create System Files and Directories.108beta # [1201574.464427] beta systemd[1]: Starting Rebuild Journal Catalog...109beta # [1201574.465164] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...110beta # [1201574.477999] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.111beta # [1201574.485691] beta systemd[1]: Finished Rebuild Journal Catalog.112beta # [1201574.486817] beta systemd[1]: Starting Update is Completed...113beta # [1201574.498278] beta systemd[1]: Finished Update is Completed.114beta # [1201574.541797] beta systemd[1]: Finished Firewall.115beta # [1201574.541964] beta systemd[1]: Reached target Preparation for Network.116beta # [1201574.542202] beta systemd[1]: Listening on Network Management Resolve Hook Socket.117beta # [1201574.543288] beta systemd[1]: Starting Network Management...118beta # [1201574.911808] beta systemd[1]: Finished Save Transient machine-id to Disk.119beta # [1201575.284943] beta systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted120beta # [1201575.285036] beta systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted121beta # [1201575.294574] beta systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.122beta # [1201575.294745] beta systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.123beta # [1201575.294907] beta systemd-networkd[207]: lo: Link UP124beta # [1201575.294913] beta systemd-networkd[207]: lo: Gained carrier125beta # [1201575.295141] beta systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.126beta # [1201575.295612] beta systemd[1]: Started Network Management.127beta # [1201575.295643] beta systemd-networkd[207]: eth1: Link UP128beta # [1201575.295966] beta systemd-networkd[207]: eth1: Gained carrier129beta # [1201575.297188] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...130beta # [1201575.347196] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.131beta # [1201575.369400] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.132gamma # [1201574.376772] gamma systemd-journald[90]: Journal started133gamma # [1201574.376827] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/8693a13a5fe24b2f932ad323bfb1e59a) is 8M, max 2.5G, 2.4G free.134gamma # [1201574.383950] gamma systemd[1]: Finished Apply Kernel Variables.135gamma # [1201574.395984] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.136gamma # [1201574.410730] gamma systemd[1]: Starting Flush Journal to Persistent Storage...137gamma # [1201574.411769] gamma systemd[1]: Starting Network Name Resolution...138gamma # [1201574.412477] gamma systemd[1]: Starting Create Static Device Nodes in /dev...139gamma # [1201574.421965] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/8693a13a5fe24b2f932ad323bfb1e59a is 1.396ms for 7 entries.140gamma # [1201574.421965] gamma systemd-journald[90]: System Journal (/var/log/journal/8693a13a5fe24b2f932ad323bfb1e59a) is 8M, max 4G, 3.9G free.141gamma # [1201574.441086] gamma systemd[1]: Finished Flush Journal to Persistent Storage.142gamma # [1201574.441360] gamma systemd[1]: Finished Create Static Device Nodes in /dev.143gamma # [1201574.442496] gamma systemd[1]: Reached target Preparation for Local File Systems.144gamma # [1201574.442636] gamma systemd[1]: Reached target Local File Systems.145gamma # [1201574.443488] gamma systemd[1]: Listening on Boot Loader Control Service Socket.146gamma # [1201574.443541] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container147gamma # [1201574.444432] gamma systemd[1]: Starting Save Transient machine-id to Disk...148gamma # [1201574.445126] gamma systemd[1]: Starting Create System Files and Directories...149gamma # [1201574.445159] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys150gamma # [1201574.462771] gamma systemd-tmpfiles[149]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted151gamma # [1201574.462992] gamma systemd-tmpfiles[149]: fchmod() of /var/log/journal failed: Operation not permitted152gamma # [1201574.463169] gamma systemd-tmpfiles[149]: fchmod() of /var/log/journal/8693a13a5fe24b2f932ad323bfb1e59a failed: Operation not permitted153gamma # [1201574.463405] gamma systemd-tmpfiles[149]: fchmod() of /run/log/journal failed: Operation not permitted154gamma # [1201574.465129] gamma systemd[1]: Finished Create System Files and Directories.155gamma # [1201574.466240] gamma systemd[1]: Starting Rebuild Journal Catalog...156gamma # [1201574.466952] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...157gamma # [1201574.478851] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.158gamma # [1201574.486693] gamma systemd[1]: Finished Rebuild Journal Catalog.159gamma # [1201574.487718] gamma systemd[1]: Starting Update is Completed...160gamma # [1201574.499401] gamma systemd[1]: Finished Update is Completed.161gamma # [1201574.542954] gamma systemd[1]: Finished Firewall.162gamma # [1201574.543124] gamma systemd[1]: Reached target Preparation for Network.163gamma # [1201574.543350] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.164gamma # [1201574.544417] gamma systemd[1]: Starting Network Management...165gamma # [1201574.913569] gamma systemd[1]: Finished Save Transient machine-id to Disk.166gamma # [1201575.284943] gamma systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted167gamma # [1201575.285047] gamma systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted168gamma # [1201575.294709] gamma systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.169gamma # [1201575.294881] gamma systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.170gamma # [1201575.295033] gamma systemd-networkd[207]: lo: Link UP171gamma # [1201575.295037] gamma systemd-networkd[207]: lo: Gained carrier172gamma # [1201575.295207] gamma systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.173gamma # [1201575.295610] gamma systemd[1]: Started Network Management.174gamma # [1201575.295917] gamma systemd-networkd[207]: eth1: Link UP175gamma # [1201575.296176] gamma systemd-networkd[207]: eth1: Gained carrier176gamma # [1201575.296914] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...177gamma # [1201575.346468] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.178gamma # [1201575.371963] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.179alpha # [1201575.672134] alpha systemd-resolved[125]: Positive Trust Anchors:180alpha # [1201575.672145] alpha systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d181alpha # [1201575.672147] alpha systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16182alpha # [1201575.672181] alpha systemd-resolved[125]: 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 test183alpha # [1201575.693809] alpha systemd-resolved[125]: Using system hostname 'alpha'.184alpha # [1201575.695182] alpha systemd[1]: Started Network Name Resolution.185alpha # [1201575.695275] alpha systemd[1]: Reached target Network.186alpha # [1201575.695360] alpha systemd[1]: Reached target System Initialization.187alpha # [1201575.695423] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container188alpha # [1201575.695454] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.189alpha # [1201575.695478] alpha systemd[1]: Reached target Timer Units.190alpha # [1201575.695626] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.191alpha # [1201575.695770] alpha systemd[1]: Listening on Nix Daemon Socket.192alpha # [1201575.695904] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.193alpha # [1201575.695935] alpha systemd[1]: Reached target Socket Units.194alpha # [1201575.695987] alpha systemd[1]: Reached target Basic System.195alpha # [1201575.728438] alpha systemd[1]: Starting data mesher daemon...196alpha # [1201575.729474] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...197alpha # [1201575.730518] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...198alpha # [1201575.732179] alpha systemd[1]: Starting D-Bus System Message Bus...199alpha # [1201575.752105] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.200beta # [1201575.693510] beta systemd-resolved[125]: Positive Trust Anchors:201beta # [1201575.693521] beta systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d202beta # [1201575.693525] beta systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16203beta # [1201575.693560] beta systemd-resolved[125]: 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 test204beta # [1201575.716190] beta systemd-resolved[125]: Using system hostname 'beta'.205beta # [1201575.717614] beta systemd[1]: Started Network Name Resolution.206beta # [1201575.717747] beta systemd[1]: Reached target Network.207beta # [1201575.717866] beta systemd[1]: Reached target System Initialization.208beta # [1201575.717962] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container209beta # [1201575.718013] beta systemd[1]: Started Daily Cleanup of Temporary Directories.210beta # [1201575.718057] beta systemd[1]: Reached target Timer Units.211beta # [1201575.718281] beta systemd[1]: Listening on D-Bus System Message Bus Socket.212beta # [1201575.718484] beta systemd[1]: Listening on Nix Daemon Socket.213beta # [1201575.718690] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.214beta # [1201575.718737] beta systemd[1]: Reached target Socket Units.215beta # [1201575.718814] beta systemd[1]: Reached target Basic System.216beta # [1201575.728770] beta systemd[1]: Starting data mesher daemon...217beta # [1201575.730147] beta systemd[1]: Starting Import lastlog data into lastlog2 database...218beta # [1201575.732827] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...219beta # [1201575.734031] beta systemd[1]: Starting D-Bus System Message Bus...220beta # [1201575.752801] beta systemd[1]: Finished Import lastlog data into lastlog2 database.221beta # [1201576.020475] beta nsncd[215]: Sep 18 05:13:16.006 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"222beta # [1201576.020459] beta systemd[1]: Started Name Service Cache Daemon (nsncd).223gamma # [1201575.663421] gamma systemd-resolved[125]: Positive Trust Anchors:224gamma # [1201575.663433] gamma systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d225gamma # [1201575.663435] gamma systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16226gamma # [1201575.663471] gamma systemd-resolved[125]: 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 test227gamma # [1201575.685182] gamma systemd-resolved[125]: Using system hostname 'gamma'.228gamma # [1201575.686570] gamma systemd[1]: Started Network Name Resolution.229gamma # [1201575.686698] gamma systemd[1]: Reached target Network.230gamma # [1201575.686823] gamma systemd[1]: Reached target System Initialization.231gamma # [1201575.686916] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container232gamma # [1201575.686967] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.233gamma # [1201575.687004] gamma systemd[1]: Reached target Timer Units.234gamma # [1201575.687234] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.235gamma # [1201575.687440] gamma systemd[1]: Listening on Nix Daemon Socket.236gamma # [1201575.687654] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.237gamma # [1201575.687702] gamma systemd[1]: Reached target Socket Units.238gamma # [1201575.687779] gamma systemd[1]: Reached target Basic System.239gamma # [1201575.689937] gamma systemd[1]: Starting data mesher daemon...240gamma # [1201575.691343] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...241gamma # [1201575.692948] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...242gamma # [1201575.695140] gamma systemd[1]: Starting D-Bus System Message Bus...243gamma # [1201575.745016] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.244gamma # [1201576.029909] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).245gamma # [1201576.030103] gamma nsncd[215]: Sep 18 05:13:16.015 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"246gamma # [1201576.029985] gamma systemd[1]: Reached target Host and Network Name Lookups.247gamma # [1201576.030107] gamma systemd[1]: Reached target User and Group Name Lookups.248alpha # [1201576.010044] alpha nsncd[216]: Sep 18 05:13:15.995 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"249alpha # [1201576.010144] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).250alpha # [1201576.010230] alpha systemd[1]: Reached target Host and Network Name Lookups.251gamma # [1201576.031938] gamma systemd[1]: Starting User Login Management...252gamma # [1201576.033270] gamma systemd[1]: Starting Permit User Sessions...253beta # [1201576.020535] beta systemd[1]: Reached target Host and Network Name Lookups.254alpha # [1201576.010323] alpha systemd[1]: Reached target User and Group Name Lookups.255beta # [1201576.020609] beta systemd[1]: Reached target User and Group Name Lookups.256gamma # [1201576.044047] gamma systemd[1]: Finished Permit User Sessions.257beta # [1201576.025310] beta systemd[1]: Starting User Login Management...258gamma # [1201576.045263] gamma systemd[1]: Started Console Getty.259alpha # [1201576.025406] alpha systemd[1]: Starting User Login Management...260gamma # [1201576.045311] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0261alpha # [1201576.026714] alpha systemd[1]: Starting Permit User Sessions...262gamma # [1201576.045335] gamma systemd[1]: Reached target Login Prompts.263alpha # [1201576.037908] alpha systemd[1]: Finished Permit User Sessions.264alpha # [1201576.039114] alpha systemd[1]: Started Console Getty.265alpha # [1201576.039163] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0266alpha # [1201576.039185] alpha systemd[1]: Reached target Login Prompts.267alpha # [1201576.222328] alpha dbus-broker-launch[217]: Looking up NSS user entry for 'systemd-timesync'...268beta # [1201576.026835] beta systemd[1]: Starting Permit User Sessions...269gamma # [1201576.229752] gamma dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...270beta # [1201576.037514] beta systemd[1]: Finished Permit User Sessions.271alpha # [1201576.223682] alpha dbus-broker-launch[217]: NSS returned no entry for 'systemd-timesync'272gamma # [1201576.231328] gamma dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'273beta # [1201576.039558] beta systemd[1]: Started Console Getty.274beta # [1201576.039611] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0275alpha # [1201576.223682] alpha dbus-broker-launch[217]: Invalid user-name in /nix/store/b4xjl9rb701f7m46l1pg5x6fqp1kib70-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"276gamma # [1201576.231328] gamma dbus-broker-launch[216]: Invalid user-name in /nix/store/b4xjl9rb701f7m46l1pg5x6fqp1kib70-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"277alpha # [1201576.224290] alpha systemd[1]: Started D-Bus System Message Bus.278gamma # [1201576.231756] gamma systemd[1]: Started D-Bus System Message Bus.279beta # [1201576.039635] beta systemd[1]: Reached target Login Prompts.280gamma # [1201576.238845] gamma dbus-broker-launch[216]: Ready281alpha # [1201576.233173] alpha dbus-broker-launch[217]: Ready282beta # [1201576.227293] beta dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...283beta # [1201576.228524] beta dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'284beta # [1201576.228524] beta dbus-broker-launch[216]: Invalid user-name in /nix/store/b4xjl9rb701f7m46l1pg5x6fqp1kib70-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"285beta # [1201576.228946] beta systemd[1]: Started D-Bus System Message Bus.286beta # [1201576.236855] beta dbus-broker-launch[216]: Ready287alpha # [1201576.352175] alpha systemd-networkd[208]: eth1: Gained IPv6LL288gamma # [1201576.674774] gamma data-mesher[213]: time=2026-09-18T05:13:16.660Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]289gamma # [1201576.676575] gamma data-mesher[213]: time=2026-09-18T05:13:16.662Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC290gamma # [1201576.676575] gamma data-mesher[213]: time=2026-09-18T05:13:16.662Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml291gamma # [1201576.708578] gamma data-mesher[213]: time=2026-09-18T05:13:16.694Z level=INFO msg="checking file integrity"292gamma # [1201576.708720] gamma data-mesher[213]: time=2026-09-18T05:13:16.694Z level=INFO msg="file integrity check complete"293gamma # [1201576.713694] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="libp2p host created" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.3/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::3/tcp/7946]"294gamma # [1201576.713694] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="registered HTTP route" method=GET path=/files295gamma # [1201576.713694] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name296gamma # [1201576.713694] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name297gamma # [1201576.713694] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="starting server"298gamma # [1201576.713830] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="waiting for DHT to populate" delay=10s299gamma # [1201576.713898] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="HTTP server listening" address=[::1]:7331300gamma # [1201576.713936] gamma data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331301gamma # [1201576.719563] gamma data-mesher[213]: time=2026-09-18T05:13:16.705Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946302gamma # [1201576.727817] gamma data-mesher[213]: time=2026-09-18T05:13:16.713Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip4/192.168.1.2/tcp/7946303gamma # [1201576.914058] gamma systemd-logind[232]: New seat seat0.304gamma # [1201576.914309] gamma systemd[1]: Started User Login Management.305gamma # [1201576.916504] gamma systemd[1]: Starting linger-users.service...306alpha # [1201576.667041] alpha data-mesher[214]: time=2026-09-18T05:13:16.652Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]307alpha # [1201576.668109] alpha data-mesher[214]: time=2026-09-18T05:13:16.653Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg308alpha # [1201576.668109] alpha data-mesher[214]: time=2026-09-18T05:13:16.653Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml309alpha # [1201576.708804] alpha data-mesher[214]: time=2026-09-18T05:13:16.694Z level=INFO msg="checking file integrity"310alpha # [1201576.708934] alpha data-mesher[214]: time=2026-09-18T05:13:16.694Z level=INFO msg="file integrity check complete"311alpha # [1201576.713477] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="libp2p host created" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.1/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::1/tcp/7946]"312alpha # [1201576.713536] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="registered HTTP route" method=GET path=/files313alpha # [1201576.713536] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name314alpha # [1201576.713536] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name315alpha # [1201576.713536] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="starting server"316alpha # [1201576.713657] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="waiting for DHT to populate" delay=10s317alpha # [1201576.713748] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="HTTP server listening" address=[::1]:7331318alpha # [1201576.713787] alpha data-mesher[214]: time=2026-09-18T05:13:16.699Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331319alpha # [1201576.721701] alpha data-mesher[214]: time=2026-09-18T05:13:16.707Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946320beta # [1201576.675362] beta data-mesher[213]: time=2026-09-18T05:13:16.661Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]321beta # [1201576.676467] beta data-mesher[213]: time=2026-09-18T05:13:16.662Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC322beta # [1201576.676525] beta data-mesher[213]: time=2026-09-18T05:13:16.662Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml323beta # [1201576.708571] beta data-mesher[213]: time=2026-09-18T05:13:16.694Z level=INFO msg="checking file integrity"324beta # [1201576.708698] beta data-mesher[213]: time=2026-09-18T05:13:16.694Z level=INFO msg="file integrity check complete"325beta # [1201576.712727] beta data-mesher[213]: time=2026-09-18T05:13:16.698Z level=INFO msg="libp2p host created" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946]"326beta # [1201576.712773] beta data-mesher[213]: time=2026-09-18T05:13:16.698Z level=INFO msg="registered HTTP route" method=GET path=/files327beta # [1201576.712773] beta data-mesher[213]: time=2026-09-18T05:13:16.698Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name328beta # [1201576.712773] beta data-mesher[213]: time=2026-09-18T05:13:16.698Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name329beta # [1201576.712773] beta data-mesher[213]: time=2026-09-18T05:13:16.698Z level=INFO msg="starting server"330beta # [1201576.712891] beta data-mesher[213]: time=2026-09-18T05:13:16.698Z level=INFO msg="waiting for DHT to populate" delay=10s331beta # [1201576.713200] beta data-mesher[213]: time=2026-09-18T05:13:16.698Z level=INFO msg="HTTP server listening" address=[::1]:7331332beta # [1201576.713557] beta data-mesher[213]: time=2026-09-18T05:13:16.699Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331333beta # [1201576.729326] beta data-mesher[213]: time=2026-09-18T05:13:16.715Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946334beta # [1201576.887354] beta systemd-logind[232]: New seat seat0.335beta # [1201576.887616] beta systemd[1]: Started User Login Management.336beta # [1201576.893855] beta systemd[1]: Starting linger-users.service...337beta # [1201576.913588] beta systemd[1]: linger-users.service: Deactivated successfully.338beta # [1201576.913761] beta systemd[1]: Finished linger-users.service.339alpha # [1201576.936767] alpha systemd-logind[233]: New seat seat0.340alpha # [1201576.936956] alpha systemd[1]: Started User Login Management.341alpha # [1201576.938032] alpha systemd[1]: Starting linger-users.service...342alpha # [1201576.949123] alpha systemd[1]: linger-users.service: Deactivated successfully.343alpha # [1201576.949281] alpha systemd[1]: Finished linger-users.service.344gamma # [1201576.934511] gamma systemd[1]: linger-users.service: Deactivated successfully.345gamma # [1201576.934730] gamma systemd[1]: Finished linger-users.service.346beta # [1201577.088151] beta systemd-networkd[207]: eth1: Gained IPv6LL347gamma # [1201577.344132] gamma systemd-networkd[207]: eth1: Gained IPv6LL348beta # [1201577.736669] beta data-mesher[213]: time=2026-09-18T05:13:17.722Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip6/2001:db8:1::1/tcp/7946349alpha # [1201577.736043] alpha data-mesher[214]: time=2026-09-18T05:13:17.721Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/7946350alpha: still waiting for container 'alpha' to reach ready state...351alpha # [1201586.713793] alpha data-mesher[214]: time=2026-09-18T05:13:26.699Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC352alpha # [1201586.713793] alpha data-mesher[214]: time=2026-09-18T05:13:26.699Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC353gamma # [1201586.714404] gamma data-mesher[213]: time=2026-09-18T05:13:26.700Z level=INFO msg="performing state exchange with peers on join" count=1354beta # [1201586.713127] beta data-mesher[213]: time=2026-09-18T05:13:26.698Z level=INFO msg="performing state exchange with peers on join" count=1355gamma # [1201586.714404] gamma data-mesher[213]: time=2026-09-18T05:13:26.700Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s356beta # [1201586.713127] beta data-mesher[213]: time=2026-09-18T05:13:26.698Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s357beta # [1201586.714105] beta data-mesher[213]: time=2026-09-18T05:13:26.699Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg358alpha # [1201586.713793] alpha data-mesher[214]: time=2026-09-18T05:13:26.699Z level=INFO msg="performing state exchange with peers on join" count=1359beta # [1201586.714105] beta data-mesher[213]: time=2026-09-18T05:13:26.699Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg360alpha # [1201586.714312] alpha data-mesher[214]: time=2026-09-18T05:13:26.699Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s361beta # [1201586.714184] beta data-mesher[213]: time=2026-09-18T05:13:26.699Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg362alpha # [1201586.714355] alpha data-mesher[214]: time=2026-09-18T05:13:26.700Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC363beta # [1201586.714184] beta data-mesher[213]: time=2026-09-18T05:13:26.699Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s364alpha # [1201586.714355] alpha data-mesher[214]: time=2026-09-18T05:13:26.700Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s365beta # [1201586.714238] beta data-mesher[213]: time=2026-09-18T05:13:26.700Z level=INFO msg="server started"366alpha # [1201586.714413] alpha data-mesher[214]: time=2026-09-18T05:13:26.700Z level=INFO msg="server started"367beta # [1201586.714380] beta data-mesher[213]: time=2026-09-18T05:13:26.700Z level=INFO msg="starting expired-file sweeper" interval=1m0s368gamma # [1201586.715254] gamma data-mesher[213]: time=2026-09-18T05:13:26.701Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC369beta # [1201586.714448] beta systemd[1]: Started data mesher daemon.370beta # [1201586.714771] beta systemd[1]: Reached target Multi-User System.371alpha # [1201586.714540] alpha data-mesher[214]: time=2026-09-18T05:13:26.700Z level=INFO msg="starting expired-file sweeper" interval=1m0s372beta # [1201586.714932] beta data-mesher[213]: time=2026-09-18T05:13:26.700Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC373gamma # [1201586.715254] gamma data-mesher[213]: time=2026-09-18T05:13:26.701Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s374alpha # [1201586.714630] alpha systemd[1]: Started data mesher daemon.375gamma # [1201586.715358] gamma data-mesher[213]: time=2026-09-18T05:13:26.701Z level=INFO msg="server started"376alpha # [1201586.714940] alpha systemd[1]: Reached target Multi-User System.377gamma # [1201586.715457] gamma data-mesher[213]: time=2026-09-18T05:13:26.701Z level=INFO msg="starting expired-file sweeper" interval=1m0s378alpha # [1201586.715129] alpha systemd[1]: Startup finished in 12.788s.379gamma # [1201586.715596] gamma systemd[1]: Started data mesher daemon.380beta # [1201586.715003] beta data-mesher[213]: time=2026-09-18T05:13:26.700Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC381beta # [1201586.715080] beta systemd[1]: Startup finished in 12.778s.382gamma # [1201586.716129] gamma systemd[1]: Reached target Multi-User System.383gamma # [1201586.716399] gamma systemd[1]: Startup finished in 12.779s.384alpha: (finished: waiting for unit data-mesher.service, in 13.68 seconds)385beta: waiting for unit data-mesher.service386beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)387gamma: waiting for unit data-mesher.service388gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)389alpha: must succeed: echo -n 'hello world' > /tmp/test_file390alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)391alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key392alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.04 seconds)393??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.394 File "/nix/store/v9vdqyj6qkb502yxrxqyp79njk9qi1ib-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39395alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file396??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.397 File "/nix/store/v9vdqyj6qkb502yxrxqyp79njk9qi1ib-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39398alpha: (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)399beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file400alpha # [1201587.164291] alpha data-mesher[214]: time=2026-09-18T05:13:27.150Z level=INFO msg=http_request uri=/files/test_file status=204401beta # [1201591.716197] beta data-mesher[213]: time=2026-09-18T05:13:31.701Z level=DEBUG msg="attempting push/pull" peer_count=2402beta # [1201591.716894] beta data-mesher[213]: time=2026-09-18T05:13:31.702Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s403beta # [1201591.716987] beta data-mesher[213]: time=2026-09-18T05:13:31.702Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC404beta # [1201591.716987] beta data-mesher[213]: time=2026-09-18T05:13:31.702Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s405alpha # [1201591.716718] alpha data-mesher[214]: time=2026-09-18T05:13:31.702Z level=DEBUG msg="attempting push/pull" peer_count=2406beta # [1201591.717094] beta data-mesher[213]: time=2026-09-18T05:13:31.702Z level=DEBUG msg="push/pull successful" interval=5s407alpha # [1201591.716718] alpha data-mesher[214]: time=2026-09-18T05:13:31.702Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s408gamma # [1201591.716225] gamma data-mesher[213]: time=2026-09-18T05:13:31.701Z level=DEBUG msg="attempting push/pull" peer_count=2409alpha # [1201591.717559] alpha data-mesher[214]: time=2026-09-18T05:13:31.702Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC410gamma # [1201591.716890] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s411alpha # [1201591.717559] alpha data-mesher[214]: time=2026-09-18T05:13:31.702Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC412gamma # [1201591.716890] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC413alpha # [1201591.717559] alpha data-mesher[214]: time=2026-09-18T05:13:31.703Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC414gamma # [1201591.716890] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC415alpha # [1201591.717559] alpha data-mesher[214]: time=2026-09-18T05:13:31.703Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s416gamma # [1201591.717092] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg417alpha # [1201591.717559] alpha data-mesher[214]: time=2026-09-18T05:13:31.703Z level=DEBUG msg="push/pull successful" interval=5s418gamma # [1201591.717092] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file419alpha # [1201591.717802] alpha data-mesher[214]: time=2026-09-18T05:13:31.703Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file420gamma # [1201591.717207] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s421gamma # [1201591.717207] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=DEBUG msg="push/pull successful" interval=5s422gamma # [1201591.717207] gamma data-mesher[213]: time=2026-09-18T05:13:31.702Z level=INFO msg="scheduling file download" name=test_file423alpha # [1201591.718799] alpha data-mesher[214]: time=2026-09-18T05:13:31.704Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file424gamma # [1201591.717350] gamma data-mesher[213]: time=2026-09-18T05:13:31.703Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg425gamma # [1201591.717350] gamma data-mesher[213]: time=2026-09-18T05:13:31.703Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg426gamma # [1201591.717350] gamma data-mesher[213]: time=2026-09-18T05:13:31.703Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file427gamma # [1201591.717350] gamma data-mesher[213]: time=2026-09-18T05:13:31.703Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-18 05:13:27.135 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg428gamma # [1201591.733085] gamma data-mesher[213]: time=2026-09-18T05:13:31.718Z level=INFO msg="download complete" name=test_file signed_at="2026-09-18 05:13:27.135 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=15.825619ms429beta # [1201596.717556] beta data-mesher[213]: time=2026-09-18T05:13:36.703Z level=DEBUG msg="attempting push/pull" peer_count=2430beta # [1201596.717556] beta data-mesher[213]: time=2026-09-18T05:13:36.703Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s431beta # [1201596.718266] beta data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC432beta # [1201596.718312] beta data-mesher[213]: time=2026-09-18T05:13:36.704Z level=DEBUG msg="new file detected" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC name=test_file name=test_file433beta # [1201596.718312] beta data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s434beta # [1201596.718371] beta data-mesher[213]: time=2026-09-18T05:13:36.704Z level=DEBUG msg="push/pull successful" interval=5s435beta # [1201596.718401] beta data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="scheduling file download" name=test_file436beta # [1201596.718506] beta data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-18 05:13:27.135 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC437beta # [1201596.733083] beta data-mesher[213]: time=2026-09-18T05:13:36.718Z level=INFO msg="download complete" name=test_file signed_at="2026-09-18 05:13:27.135 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC written=true elapsed=14.621002ms438gamma # [1201596.717610] gamma data-mesher[213]: time=2026-09-18T05:13:36.703Z level=DEBUG msg="attempting push/pull" peer_count=2439gamma # [1201596.717610] gamma data-mesher[213]: time=2026-09-18T05:13:36.703Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s440gamma # [1201596.718334] gamma data-mesher[213]: time=2026-09-18T05:13:36.703Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC441gamma # [1201596.718334] gamma data-mesher[213]: time=2026-09-18T05:13:36.703Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC442gamma # [1201596.718507] gamma data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg443gamma # [1201596.718507] gamma data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s444gamma # [1201596.718645] gamma data-mesher[213]: time=2026-09-18T05:13:36.704Z level=DEBUG msg="push/pull successful" interval=5s445gamma # [1201596.718763] gamma data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg446gamma # [1201596.718826] gamma data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg447gamma # [1201596.718968] gamma data-mesher[213]: time=2026-09-18T05:13:36.704Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file448gamma # [1201596.721103] gamma data-mesher[213]: time=2026-09-18T05:13:36.706Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file449alpha # [1201596.718131] alpha data-mesher[214]: time=2026-09-18T05:13:36.703Z level=DEBUG msg="attempting push/pull" peer_count=2450alpha # [1201596.718131] alpha data-mesher[214]: time=2026-09-18T05:13:36.703Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC451alpha # [1201596.718865] alpha data-mesher[214]: time=2026-09-18T05:13:36.703Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s452alpha # [1201596.718865] alpha data-mesher[214]: time=2026-09-18T05:13:36.703Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC453alpha # [1201596.719056] alpha data-mesher[214]: time=2026-09-18T05:13:36.704Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC454alpha # [1201596.719131] alpha data-mesher[214]: time=2026-09-18T05:13:36.704Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s455alpha # [1201596.719131] alpha data-mesher[214]: time=2026-09-18T05:13:36.704Z level=DEBUG msg="push/pull successful" interval=5s456beta: (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 10.09 seconds)457gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file458gamma: (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)459beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key460beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.07 seconds)461alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file462beta # [1201597.340225] beta data-mesher[213]: time=2026-09-18T05:13:37.325Z level=INFO msg=http_request uri=/files/test_file status=204463beta # [1201601.718863] beta data-mesher[213]: time=2026-09-18T05:13:41.704Z level=DEBUG msg="attempting push/pull" peer_count=2464beta # [1201601.719579] beta data-mesher[213]: time=2026-09-18T05:13:41.704Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s465beta # [1201601.720187] beta data-mesher[213]: time=2026-09-18T05:13:41.705Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC466beta # [1201601.720187] beta data-mesher[213]: time=2026-09-18T05:13:41.706Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC467beta # [1201601.731812] beta data-mesher[213]: time=2026-09-18T05:13:41.717Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC468beta # [1201601.731935] beta data-mesher[213]: time=2026-09-18T05:13:41.717Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s469beta # [1201601.731935] beta data-mesher[213]: time=2026-09-18T05:13:41.717Z level=DEBUG msg="push/pull successful" interval=5s470gamma # [1201601.719392] gamma data-mesher[213]: time=2026-09-18T05:13:41.705Z level=DEBUG msg="attempting push/pull" peer_count=2471gamma # [1201601.720093] gamma data-mesher[213]: time=2026-09-18T05:13:41.705Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s472gamma # [1201601.720093] gamma data-mesher[213]: time=2026-09-18T05:13:41.705Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC473gamma # [1201601.720093] gamma data-mesher[213]: time=2026-09-18T05:13:41.705Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC474gamma # [1201601.720093] gamma data-mesher[213]: time=2026-09-18T05:13:41.705Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg475gamma # [1201601.720093] gamma data-mesher[213]: time=2026-09-18T05:13:41.705Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg476gamma # [1201601.720556] gamma data-mesher[213]: time=2026-09-18T05:13:41.706Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC477gamma # [1201601.731367] gamma data-mesher[213]: time=2026-09-18T05:13:41.717Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true478gamma # [1201601.731507] gamma data-mesher[213]: time=2026-09-18T05:13:41.717Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=false479gamma # [1201601.731507] gamma data-mesher[213]: time=2026-09-18T05:13:41.717Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s480gamma # [1201601.731507] gamma data-mesher[213]: time=2026-09-18T05:13:41.717Z level=DEBUG msg="push/pull successful" interval=5s481alpha # [1201601.719319] alpha data-mesher[214]: time=2026-09-18T05:13:41.705Z level=DEBUG msg="attempting push/pull" peer_count=2482alpha # [1201601.720045] alpha data-mesher[214]: time=2026-09-18T05:13:41.705Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s483alpha # [1201601.720538] alpha data-mesher[214]: time=2026-09-18T05:13:41.706Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC484alpha # [1201601.720538] alpha data-mesher[214]: time=2026-09-18T05:13:41.706Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s485alpha # [1201601.720538] alpha data-mesher[214]: time=2026-09-18T05:13:41.706Z level=DEBUG msg="push/pull successful" interval=5s486beta # [1201606.732576] beta data-mesher[213]: time=2026-09-18T05:13:46.718Z level=DEBUG msg="attempting push/pull" peer_count=2487beta # [1201606.732576] beta data-mesher[213]: time=2026-09-18T05:13:46.718Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s488beta # [1201606.733291] beta data-mesher[213]: time=2026-09-18T05:13:46.718Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC489beta # [1201606.733291] beta data-mesher[213]: time=2026-09-18T05:13:46.718Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC490beta # [1201606.733291] beta data-mesher[213]: time=2026-09-18T05:13:46.719Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC491beta # [1201606.733291] beta data-mesher[213]: time=2026-09-18T05:13:46.719Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s492beta # [1201606.733291] beta data-mesher[213]: time=2026-09-18T05:13:46.719Z level=DEBUG msg="push/pull successful" interval=5s493gamma # [1201606.721101] gamma data-mesher[213]: time=2026-09-18T05:13:46.706Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg494gamma # [1201606.721101] gamma data-mesher[213]: time=2026-09-18T05:13:46.706Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg495gamma # [1201606.733545] gamma data-mesher[213]: time=2026-09-18T05:13:46.718Z level=DEBUG msg="attempting push/pull" peer_count=2496gamma # [1201606.733545] gamma data-mesher[213]: time=2026-09-18T05:13:46.718Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s497gamma # [1201606.733545] gamma data-mesher[213]: time=2026-09-18T05:13:46.718Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC498gamma # [1201606.733545] gamma data-mesher[213]: time=2026-09-18T05:13:46.718Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC499gamma # [1201606.733545] gamma data-mesher[213]: time=2026-09-18T05:13:46.719Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC500gamma # [1201606.733545] gamma data-mesher[213]: time=2026-09-18T05:13:46.719Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s501gamma # [1201606.733545] gamma data-mesher[213]: time=2026-09-18T05:13:46.719Z level=DEBUG msg="push/pull successful" interval=5s502alpha # [1201606.720584] alpha data-mesher[214]: time=2026-09-18T05:13:46.706Z level=DEBUG msg="attempting push/pull" peer_count=2503alpha # [1201606.720584] alpha data-mesher[214]: time=2026-09-18T05:13:46.706Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s504alpha # [1201606.721412] alpha data-mesher[214]: time=2026-09-18T05:13:46.707Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC505alpha # [1201606.724625] alpha data-mesher[214]: time=2026-09-18T05:13:46.710Z level=DEBUG msg="imported tombstone" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC name=test_file written=true506alpha # [1201606.724625] alpha data-mesher[214]: time=2026-09-18T05:13:46.710Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s507alpha # [1201606.724762] alpha data-mesher[214]: time=2026-09-18T05:13:46.710Z level=DEBUG msg="push/pull successful" interval=5s508alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.09 seconds)509beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file510beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)511gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file512gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)513alpha: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub514alpha: (finished: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)515alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file516alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)517alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert518alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)519alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE520alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 0.01 seconds)521beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE522alpha # [1201607.484552] alpha data-mesher[214]: time=2026-09-18T05:13:47.470Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=204523beta # [1201611.726399] beta data-mesher[213]: time=2026-09-18T05:13:51.712Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg524beta # [1201611.726399] beta data-mesher[213]: time=2026-09-18T05:13:51.712Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg525beta # [1201611.727144] beta data-mesher[213]: time=2026-09-18T05:13:51.712Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE526beta # [1201611.727144] beta data-mesher[213]: time=2026-09-18T05:13:51.712Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE527beta # [1201611.727144] beta data-mesher[213]: time=2026-09-18T05:13:51.712Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-18 05:13:47.467 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg528beta # [1201611.731583] beta data-mesher[213]: time=2026-09-18T05:13:51.717Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-18 05:13:47.467 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=4.498422ms529beta # [1201611.733710] beta data-mesher[213]: time=2026-09-18T05:13:51.719Z level=DEBUG msg="attempting push/pull" peer_count=2530beta # [1201611.733870] beta data-mesher[213]: time=2026-09-18T05:13:51.719Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s531beta # [1201611.735407] beta data-mesher[213]: time=2026-09-18T05:13:51.721Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg532beta # [1201611.736031] beta data-mesher[213]: time=2026-09-18T05:13:51.721Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s533beta # [1201611.736125] beta data-mesher[213]: time=2026-09-18T05:13:51.721Z level=DEBUG msg="push/pull successful" interval=5s534gamma # [1201611.733906] gamma data-mesher[213]: time=2026-09-18T05:13:51.719Z level=DEBUG msg="attempting push/pull" peer_count=2535gamma # [1201611.733906] gamma data-mesher[213]: time=2026-09-18T05:13:51.719Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s536gamma # [1201611.734959] gamma data-mesher[213]: time=2026-09-18T05:13:51.720Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg537gamma # [1201611.735560] gamma data-mesher[213]: time=2026-09-18T05:13:51.721Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE538gamma # [1201611.735560] gamma data-mesher[213]: time=2026-09-18T05:13:51.721Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s539gamma # [1201611.735705] gamma data-mesher[213]: time=2026-09-18T05:13:51.721Z level=DEBUG msg="push/pull successful" interval=5s540gamma # [1201611.735705] gamma data-mesher[213]: time=2026-09-18T05:13:51.721Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE541gamma # [1201611.735818] gamma data-mesher[213]: time=2026-09-18T05:13:51.721Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-18 05:13:47.467 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg542gamma # [1201611.740264] gamma data-mesher[213]: time=2026-09-18T05:13:51.726Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-18 05:13:47.467 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=4.497222ms543alpha # [1201611.725475] alpha data-mesher[214]: time=2026-09-18T05:13:51.711Z level=DEBUG msg="attempting push/pull" peer_count=2544alpha # [1201611.725475] alpha data-mesher[214]: time=2026-09-18T05:13:51.711Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s545alpha # [1201611.727288] alpha data-mesher[214]: time=2026-09-18T05:13:51.713Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC546alpha # [1201611.727364] alpha data-mesher[214]: time=2026-09-18T05:13:51.713Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s547alpha # [1201611.727364] alpha data-mesher[214]: time=2026-09-18T05:13:51.713Z level=DEBUG msg="push/pull successful" interval=5s548alpha # [1201611.727713] alpha data-mesher[214]: time=2026-09-18T05:13:51.713Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE549alpha # [1201611.728175] alpha data-mesher[214]: time=2026-09-18T05:13:51.713Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE550alpha # [1201611.734399] alpha data-mesher[214]: time=2026-09-18T05:13:51.720Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC551alpha # [1201611.734463] alpha data-mesher[214]: time=2026-09-18T05:13:51.720Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC552alpha # [1201611.734526] alpha data-mesher[214]: time=2026-09-18T05:13:51.720Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC553alpha # [1201611.734598] alpha data-mesher[214]: time=2026-09-18T05:13:51.720Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC554alpha # [1201611.736252] alpha data-mesher[214]: time=2026-09-18T05:13:51.722Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE555alpha # [1201611.738731] alpha data-mesher[214]: time=2026-09-18T05:13:51.724Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE556beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 5.05 seconds)557gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE558gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 0.01 seconds)559alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert560alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)561alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub562Error: failed to update file: 403 Forbidden, signer Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE= is not authorized for this file test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE563alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub, in 0.03 seconds)564(finished: run the VM test script, in 39.20 seconds)565alpha # [1201612.595592] alpha data-mesher[214]: time=2026-09-18T05:13:52.581Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=403566beta # [1201616.737019] beta data-mesher[213]: time=2026-09-18T05:13:56.722Z level=DEBUG msg="attempting push/pull" peer_count=2567beta # [1201616.737812] beta data-mesher[213]: time=2026-09-18T05:13:56.722Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s568beta # [1201616.738565] beta data-mesher[213]: time=2026-09-18T05:13:56.724Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC569beta # [1201616.739141] beta data-mesher[213]: time=2026-09-18T05:13:56.724Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s570beta # [1201616.739208] beta data-mesher[213]: time=2026-09-18T05:13:56.725Z level=DEBUG msg="push/pull successful" interval=5s571gamma # [1201616.728659] gamma data-mesher[213]: time=2026-09-18T05:13:56.714Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg572gamma # [1201616.728659] gamma data-mesher[213]: time=2026-09-18T05:13:56.714Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg573gamma # [1201616.736549] gamma data-mesher[213]: time=2026-09-18T05:13:56.722Z level=DEBUG msg="attempting push/pull" peer_count=2574gamma # [1201616.736727] gamma data-mesher[213]: time=2026-09-18T05:13:56.722Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s575gamma # [1201616.737654] gamma data-mesher[213]: time=2026-09-18T05:13:56.723Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC576gamma # [1201616.737654] gamma data-mesher[213]: time=2026-09-18T05:13:56.723Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC577gamma # [1201616.738642] gamma data-mesher[213]: time=2026-09-18T05:13:56.723Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg578gamma # [1201616.738642] gamma data-mesher[213]: time=2026-09-18T05:13:56.724Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s579gamma # [1201616.738642] gamma data-mesher[213]: time=2026-09-18T05:13:56.724Z level=DEBUG msg="push/pull successful" interval=5s580alpha # [1201616.727533] alpha data-mesher[214]: time=2026-09-18T05:13:56.713Z level=DEBUG msg="attempting push/pull" peer_count=2581alpha # [1201616.728098] alpha data-mesher[214]: time=2026-09-18T05:13:56.713Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s582alpha # [1201616.729681] alpha data-mesher[214]: time=2026-09-18T05:13:56.715Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC583alpha # [1201616.730062] alpha data-mesher[214]: time=2026-09-18T05:13:56.715Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s584alpha # [1201616.730129] alpha data-mesher[214]: time=2026-09-18T05:13:56.715Z level=DEBUG msg="push/pull successful" interval=5s585alpha # [1201616.737133] alpha data-mesher[214]: time=2026-09-18T05:13:56.722Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC586alpha # [1201616.737133] alpha data-mesher[214]: time=2026-09-18T05:13:56.722Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC587beta # [1201621.731260] beta data-mesher[213]: time=2026-09-18T05:14:01.717Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg588beta # [1201621.731260] beta data-mesher[213]: time=2026-09-18T05:14:01.717Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg589beta # [1201621.740065] beta data-mesher[213]: time=2026-09-18T05:14:01.725Z level=DEBUG msg="attempting push/pull" peer_count=2590beta # [1201621.740065] beta data-mesher[213]: time=2026-09-18T05:14:01.725Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s591beta # [1201621.741312] beta data-mesher[213]: time=2026-09-18T05:14:01.727Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC592beta # [1201621.741884] beta data-mesher[213]: time=2026-09-18T05:14:01.727Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s593beta # [1201621.741970] beta data-mesher[213]: time=2026-09-18T05:14:01.727Z level=DEBUG msg="push/pull successful" interval=5s594gamma # [1201621.739048] gamma data-mesher[213]: time=2026-09-18T05:14:01.724Z level=DEBUG msg="attempting push/pull" peer_count=2595gamma # [1201621.739048] gamma data-mesher[213]: time=2026-09-18T05:14:01.724Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s596gamma # [1201621.740315] gamma data-mesher[213]: time=2026-09-18T05:14:01.726Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg597gamma # [1201621.740469] gamma data-mesher[213]: time=2026-09-18T05:14:01.726Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC598gamma # [1201621.740469] gamma data-mesher[213]: time=2026-09-18T05:14:01.726Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC599gamma # [1201621.740922] gamma data-mesher[213]: time=2026-09-18T05:14:01.726Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s600gamma # [1201621.740995] gamma data-mesher[213]: time=2026-09-18T05:14:01.726Z level=DEBUG msg="push/pull successful" interval=5s601alpha # [1201621.730724] alpha data-mesher[214]: time=2026-09-18T05:14:01.716Z level=DEBUG msg="attempting push/pull" peer_count=2602alpha # [1201621.730724] alpha data-mesher[214]: time=2026-09-18T05:14:01.716Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s603alpha # [1201621.732443] alpha data-mesher[214]: time=2026-09-18T05:14:01.717Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC604alpha # [1201621.732443] alpha data-mesher[214]: time=2026-09-18T05:14:01.718Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s605alpha # [1201621.732443] alpha data-mesher[214]: time=2026-09-18T05:14:01.718Z level=DEBUG msg="push/pull successful" interval=5s606alpha # [1201621.739361] alpha data-mesher[214]: time=2026-09-18T05:14:01.725Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC607alpha # [1201621.739361] alpha data-mesher[214]: time=2026-09-18T05:14:01.725Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC608beta # [1201626.742068] beta data-mesher[213]: time=2026-09-18T05:14:06.727Z level=DEBUG msg="attempting push/pull" peer_count=2609beta # [1201626.742826] beta data-mesher[213]: time=2026-09-18T05:14:06.727Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s610beta # [1201626.742826] beta data-mesher[213]: time=2026-09-18T05:14:06.727Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC611beta # [1201626.742826] beta data-mesher[213]: time=2026-09-18T05:14:06.728Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC612beta # [1201626.743656] beta data-mesher[213]: time=2026-09-18T05:14:06.729Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg613beta # [1201626.744247] beta data-mesher[213]: time=2026-09-18T05:14:06.730Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s614beta # [1201626.744345] beta data-mesher[213]: time=2026-09-18T05:14:06.730Z level=DEBUG msg="push/pull successful" interval=5s615gamma # [1201626.733407] gamma data-mesher[213]: time=2026-09-18T05:14:06.719Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg616gamma # [1201626.733407] gamma data-mesher[213]: time=2026-09-18T05:14:06.719Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg617gamma # [1201626.741669] gamma data-mesher[213]: time=2026-09-18T05:14:06.727Z level=DEBUG msg="attempting push/pull" peer_count=2618gamma # [1201626.741669] gamma data-mesher[213]: time=2026-09-18T05:14:06.727Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s619gamma # [1201626.743300] gamma data-mesher[213]: time=2026-09-18T05:14:06.729Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC620gamma # [1201626.743878] gamma data-mesher[213]: time=2026-09-18T05:14:06.729Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s621gamma # [1201626.743962] gamma data-mesher[213]: time=2026-09-18T05:14:06.729Z level=DEBUG msg="push/pull successful" interval=5s622alpha # [1201626.732631] alpha data-mesher[214]: time=2026-09-18T05:14:06.718Z level=DEBUG msg="attempting push/pull" peer_count=2623alpha # [1201626.732631] alpha data-mesher[214]: time=2026-09-18T05:14:06.718Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s624alpha # [1201626.734325] alpha data-mesher[214]: time=2026-09-18T05:14:06.720Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC625alpha # [1201626.734896] alpha data-mesher[214]: time=2026-09-18T05:14:06.720Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s626alpha # [1201626.734986] alpha data-mesher[214]: time=2026-09-18T05:14:06.720Z level=DEBUG msg="push/pull successful" interval=5s627alpha # [1201626.742700] alpha data-mesher[214]: time=2026-09-18T05:14:06.728Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC628alpha # [1201626.742700] alpha data-mesher[214]: time=2026-09-18T05:14:06.728Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC629beta # [1201631.736544] beta data-mesher[213]: time=2026-09-18T05:14:11.722Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg630beta # [1201631.736544] beta data-mesher[213]: time=2026-09-18T05:14:11.722Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg631beta # [1201631.744795] beta data-mesher[213]: time=2026-09-18T05:14:11.730Z level=DEBUG msg="attempting push/pull" peer_count=2632beta # [1201631.744795] beta data-mesher[213]: time=2026-09-18T05:14:11.730Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s633beta # [1201631.746319] beta data-mesher[213]: time=2026-09-18T05:14:11.732Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC634beta # [1201631.746891] beta data-mesher[213]: time=2026-09-18T05:14:11.732Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s635beta # [1201631.746960] beta data-mesher[213]: time=2026-09-18T05:14:11.732Z level=DEBUG msg="push/pull successful" interval=5s636gamma # [1201631.744665] gamma data-mesher[213]: time=2026-09-18T05:14:11.730Z level=DEBUG msg="attempting push/pull" peer_count=2637gamma # [1201631.744665] gamma data-mesher[213]: time=2026-09-18T05:14:11.730Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s638gamma # [1201631.745454] gamma data-mesher[213]: time=2026-09-18T05:14:11.731Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC639gamma # [1201631.745454] gamma data-mesher[213]: time=2026-09-18T05:14:11.731Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC640gamma # [1201631.746255] gamma data-mesher[213]: time=2026-09-18T05:14:11.731Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg641gamma # [1201631.746835] gamma data-mesher[213]: time=2026-09-18T05:14:11.732Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s642gamma # [1201631.746835] gamma data-mesher[213]: time=2026-09-18T05:14:11.732Z level=DEBUG msg="push/pull successful" interval=5s643alpha # [1201631.735882] alpha data-mesher[214]: time=2026-09-18T05:14:11.721Z level=DEBUG msg="attempting push/pull" peer_count=2644alpha # [1201631.735882] alpha data-mesher[214]: time=2026-09-18T05:14:11.721Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s645alpha # [1201631.737546] alpha data-mesher[214]: time=2026-09-18T05:14:11.723Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC646alpha # [1201631.738128] alpha data-mesher[214]: time=2026-09-18T05:14:11.723Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s647alpha # [1201631.738209] alpha data-mesher[214]: time=2026-09-18T05:14:11.723Z level=DEBUG msg="push/pull successful" interval=5s648alpha # [1201631.745257] alpha data-mesher[214]: time=2026-09-18T05:14:11.731Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC649alpha # [1201631.745257] alpha data-mesher[214]: time=2026-09-18T05:14:11.731Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC650test script finished in 59.44s651cleanup652kill NspawnMachine (pid 53)653kill NspawnMachine (pid 54)654Container alpha terminated by signal KILL.655kill NspawnMachine (pid 55)656Container beta terminated by signal KILL.657(finished: cleanup, in 0.44 seconds)658Container gamma terminated by signal KILL.