container-test-run-data-mesher log

succeeded `container-test-run-data-mesher` `checks.aarch64-linux.data-mesher` · build #756 · 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 VMs12beta: systemd-nspawn running (pid 53)13alpha: systemd-nspawn running (pid 54)14gamma: systemd-nspawn running (pid 55)15beta: Waiting for journal at /build/vm-state-beta/var/log/journal...16alpha: Waiting for journal at /build/vm-state-alpha/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 alpha on /build/vm-state-alpha.31░ Spawning container gamma on /build/vm-state-gamma.32alpha # No journal files were found.33gamma # No journal files were found.34alpha # No journal boot entry found for the specified boot (+0).35gamma # No journal boot entry found for the specified boot (+0).36beta # No journal files were found.37beta # No journal boot entry found for the specified boot (+0).38gamma # [1582724.744461] gamma systemd-journald[90]: Journal started39alpha # [1582724.707426] alpha systemd-journald[90]: Journal started40gamma # [1582724.744513] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/f6d83f30e8a5446297c101c450c3a2b8) is 8M, max 2.5G, 2.4G free.41beta # [1582724.707509] beta systemd-journald[90]: Journal started42gamma # [1582724.759139] gamma systemd[1]: Finished Apply Kernel Variables.43beta # [1582724.707558] beta systemd-journald[90]: Runtime Journal (/run/log/journal/a19dc5def6724cc99963507d2292f8e9) is 8M, max 2.5G, 2.4G free.44gamma # [1582724.779073] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.45beta # [1582724.727226] beta systemd[1]: Finished Apply Kernel Variables.46gamma # [1582724.802273] gamma systemd[1]: Starting Flush Journal to Persistent Storage...47beta # [1582724.741990] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.48gamma # [1582724.803102] gamma systemd[1]: Starting Network Name Resolution...49beta # [1582724.756737] beta systemd[1]: Starting Flush Journal to Persistent Storage...50gamma # [1582724.803835] gamma systemd[1]: Starting Create Static Device Nodes in /dev...51beta # [1582724.757569] beta systemd[1]: Starting Network Name Resolution...52gamma # [1582724.813752] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/f6d83f30e8a5446297c101c450c3a2b8 is 1.690ms for 7 entries.53beta # [1582724.758238] beta systemd[1]: Starting Create Static Device Nodes in /dev...54gamma # [1582724.813752] gamma systemd-journald[90]: System Journal (/var/log/journal/f6d83f30e8a5446297c101c450c3a2b8) is 8M, max 4G, 3.9G free.55beta # [1582724.766760] beta systemd-journald[90]: Time spent on flushing to /var/log/journal/a19dc5def6724cc99963507d2292f8e9 is 1.675ms for 7 entries.56gamma # [1582724.836791] gamma systemd[1]: Finished Flush Journal to Persistent Storage.57beta # [1582724.766760] beta systemd-journald[90]: System Journal (/var/log/journal/a19dc5def6724cc99963507d2292f8e9) is 8M, max 4G, 3.9G free.58gamma # [1582724.837237] gamma systemd[1]: Finished Create Static Device Nodes in /dev.59beta # [1582724.797767] beta systemd[1]: Finished Flush Journal to Persistent Storage.60gamma # [1582724.838568] gamma systemd[1]: Reached target Preparation for Local File Systems.61beta # [1582724.798329] beta systemd[1]: Finished Create Static Device Nodes in /dev.62gamma # [1582724.838679] gamma systemd[1]: Reached target Local File Systems.63beta # [1582724.799678] beta systemd[1]: Reached target Preparation for Local File Systems.64gamma # [1582724.839510] gamma systemd[1]: Listening on Boot Loader Control Service Socket.65alpha # [1582724.707483] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/1cff835689464dfa8f789b99450bcc9a) is 8M, max 2.5G, 2.4G free.66gamma # [1582724.839560] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container67alpha # [1582724.729549] alpha systemd[1]: Finished Apply Kernel Variables.68gamma # [1582724.840716] gamma systemd[1]: Starting Save Transient machine-id to Disk...69alpha # [1582724.742882] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.70gamma # [1582724.841498] gamma systemd[1]: Starting Create System Files and Directories...71alpha # [1582724.757146] alpha systemd[1]: Starting Flush Journal to Persistent Storage...72beta # [1582724.799788] beta systemd[1]: Reached target Local File Systems.73alpha # [1582724.757933] alpha systemd[1]: Starting Network Name Resolution...74beta # [1582724.800647] beta systemd[1]: Listening on Boot Loader Control Service Socket.75alpha # [1582724.758569] alpha systemd[1]: Starting Create Static Device Nodes in /dev...76beta # [1582724.800701] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container77alpha # [1582724.766250] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/1cff835689464dfa8f789b99450bcc9a is 1.722ms for 7 entries.78beta # [1582724.801490] beta systemd[1]: Starting Save Transient machine-id to Disk...79alpha # [1582724.766250] alpha systemd-journald[90]: System Journal (/var/log/journal/1cff835689464dfa8f789b99450bcc9a) is 8M, max 4G, 3.9G free.80beta # [1582724.802227] beta systemd[1]: Starting Create System Files and Directories...81alpha # [1582724.797755] alpha systemd[1]: Finished Flush Journal to Persistent Storage.82beta # [1582724.802262] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys83alpha # [1582724.798309] alpha systemd[1]: Finished Create Static Device Nodes in /dev.84beta # [1582724.817936] beta systemd-tmpfiles[165]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted85alpha # [1582724.799601] alpha systemd[1]: Reached target Preparation for Local File Systems.86beta # [1582724.818109] beta systemd-tmpfiles[165]: fchmod() of /var/log/journal failed: Operation not permitted87alpha # [1582724.799706] alpha systemd[1]: Reached target Local File Systems.88beta # [1582724.818223] beta systemd-tmpfiles[165]: fchmod() of /var/log/journal/a19dc5def6724cc99963507d2292f8e9 failed: Operation not permitted89alpha # [1582724.800549] alpha systemd[1]: Listening on Boot Loader Control Service Socket.90beta # [1582724.818395] beta systemd-tmpfiles[165]: fchmod() of /run/log/journal failed: Operation not permitted91alpha # [1582724.800597] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container92beta # [1582724.825189] beta systemd[1]: Finished Create System Files and Directories.93alpha # [1582724.801479] alpha systemd[1]: Starting Save Transient machine-id to Disk...94beta # [1582724.826408] beta systemd[1]: Starting Rebuild Journal Catalog...95alpha # [1582724.802244] alpha systemd[1]: Starting Create System Files and Directories...96beta # [1582724.827133] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...97alpha # [1582724.802275] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys98beta # [1582724.838837] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.99alpha # [1582724.819748] alpha systemd-tmpfiles[165]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted100beta # [1582724.845780] beta systemd[1]: Finished Rebuild Journal Catalog.101beta # [1582724.846933] beta systemd[1]: Starting Update is Completed...102gamma # [1582724.841541] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys103alpha # [1582724.819928] alpha systemd-tmpfiles[165]: fchmod() of /var/log/journal failed: Operation not permitted104beta # [1582724.857013] beta systemd[1]: Finished Update is Completed.105alpha # [1582724.820542] alpha systemd-tmpfiles[165]: fchmod() of /var/log/journal/1cff835689464dfa8f789b99450bcc9a failed: Operation not permitted106beta # [1582724.862213] beta systemd[1]: Finished Firewall.107beta # [1582724.862373] beta systemd[1]: Reached target Preparation for Network.108gamma # [1582724.856315] gamma systemd-tmpfiles[167]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted109alpha # [1582724.820825] alpha systemd-tmpfiles[165]: fchmod() of /run/log/journal failed: Operation not permitted110beta # [1582724.862606] beta systemd[1]: Listening on Network Management Resolve Hook Socket.111gamma # [1582724.856483] gamma systemd-tmpfiles[167]: fchmod() of /var/log/journal failed: Operation not permitted112beta # [1582724.863924] beta systemd[1]: Starting Network Management...113gamma # [1582724.856593] gamma systemd-tmpfiles[167]: fchmod() of /var/log/journal/f6d83f30e8a5446297c101c450c3a2b8 failed: Operation not permitted114beta # [1582724.958208] beta systemd[1]: Finished Save Transient machine-id to Disk.115gamma # [1582724.856771] gamma systemd-tmpfiles[167]: fchmod() of /run/log/journal failed: Operation not permitted116beta # [1582725.697925] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.117gamma # [1582724.858200] gamma systemd[1]: Finished Create System Files and Directories.118alpha # [1582724.822331] alpha systemd[1]: Finished Create System Files and Directories.119gamma # [1582724.859293] gamma systemd[1]: Starting Rebuild Journal Catalog...120alpha # [1582724.823534] alpha systemd[1]: Starting Rebuild Journal Catalog...121gamma # [1582724.860267] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...122alpha # [1582724.824443] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...123gamma # [1582724.872556] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.124alpha # [1582724.836377] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.125gamma # [1582724.879040] gamma systemd[1]: Finished Rebuild Journal Catalog.126alpha # [1582724.843619] alpha systemd[1]: Finished Rebuild Journal Catalog.127gamma # [1582724.880836] gamma systemd[1]: Starting Update is Completed...128alpha # [1582724.844782] alpha systemd[1]: Starting Update is Completed...129gamma # [1582724.891348] gamma systemd[1]: Finished Update is Completed.130alpha # [1582724.856085] alpha systemd[1]: Finished Update is Completed.131gamma # [1582724.898427] gamma systemd[1]: Finished Firewall.132alpha # [1582724.863802] alpha systemd[1]: Finished Firewall.133gamma # [1582724.898585] gamma systemd[1]: Reached target Preparation for Network.134alpha # [1582724.863970] alpha systemd[1]: Reached target Preparation for Network.135gamma # [1582724.898816] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.136alpha # [1582724.864214] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.137gamma # [1582724.899839] gamma systemd[1]: Starting Network Management...138alpha # [1582724.865229] alpha systemd[1]: Starting Network Management...139gamma # [1582724.957977] gamma systemd[1]: Finished Save Transient machine-id to Disk.140alpha # [1582724.958253] alpha systemd[1]: Finished Save Transient machine-id to Disk.141alpha # [1582725.742925] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.142gamma # [1582725.740120] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.143beta # [1582726.535536] beta systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted144beta # [1582726.535630] beta systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted145beta # [1582726.547759] beta systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.146beta # [1582726.547936] beta systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.147beta # [1582726.548137] beta systemd-networkd[207]: lo: Link UP148beta # [1582726.548142] beta systemd-networkd[207]: lo: Gained carrier149beta # [1582726.548501] beta systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.150beta # [1582726.549019] beta systemd[1]: Started Network Management.151beta # [1582726.549031] beta systemd-networkd[207]: eth1: Link UP152beta # [1582726.549265] beta systemd-networkd[207]: eth1: Gained carrier153beta # [1582726.552094] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...154beta # [1582726.602447] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.155alpha # [1582726.523008] alpha systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted156alpha # [1582726.523103] alpha systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted157alpha # [1582726.532868] alpha systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.158alpha # [1582726.533044] alpha systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.159alpha # [1582726.533211] alpha systemd-networkd[207]: lo: Link UP160alpha # [1582726.533217] alpha systemd-networkd[207]: lo: Gained carrier161alpha # [1582726.533421] alpha systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.162alpha # [1582726.533823] alpha systemd[1]: Started Network Management.163alpha # [1582726.544385] alpha systemd-networkd[207]: eth1: Link UP164alpha # [1582726.544662] alpha systemd-networkd[207]: eth1: Gained carrier165alpha # [1582726.545880] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...166alpha # [1582726.582122] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.167gamma # [1582726.701528] gamma systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted168gamma # [1582726.701622] gamma systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted169gamma # [1582726.714302] gamma systemd-networkd[207]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.170gamma # [1582726.714476] gamma systemd-networkd[207]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.171gamma # [1582726.714643] gamma systemd-networkd[207]: lo: Link UP172gamma # [1582726.714646] gamma systemd-networkd[207]: lo: Gained carrier173gamma # [1582726.714825] gamma systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.174gamma # [1582726.715271] gamma systemd[1]: Started Network Management.175gamma # [1582726.715345] gamma systemd-networkd[207]: eth1: Link UP176gamma # [1582726.715672] gamma systemd-networkd[207]: eth1: Gained carrier177gamma # [1582726.716756] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...178gamma # [1582726.766486] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.179beta # [1582727.684171] beta systemd-networkd[207]: eth1: Gained IPv6LL180beta # [1582727.907374] beta systemd-resolved[134]: Positive Trust Anchors:181beta # [1582727.907386] beta systemd-resolved[134]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d182beta # [1582727.907392] beta systemd-resolved[134]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16183beta # [1582727.907439] beta systemd-resolved[134]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test184beta # [1582727.934981] beta systemd-resolved[134]: Using system hostname 'beta'.185alpha # [1582727.806429] alpha systemd-resolved[133]: Positive Trust Anchors:186alpha # [1582727.806442] alpha systemd-resolved[133]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d187alpha # [1582727.806445] alpha systemd-resolved[133]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16188alpha # [1582727.806480] alpha systemd-resolved[133]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test189alpha # [1582727.829761] alpha systemd-resolved[133]: Using system hostname 'alpha'.190alpha # [1582727.831229] alpha systemd[1]: Started Network Name Resolution.191alpha # [1582727.831345] alpha systemd[1]: Reached target Network.192alpha # [1582727.831413] alpha systemd[1]: Reached target System Initialization.193alpha # [1582727.831463] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container194alpha # [1582727.831494] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.195alpha # [1582727.831508] alpha systemd[1]: Reached target Timer Units.196alpha # [1582727.831646] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.197alpha # [1582727.831765] alpha systemd[1]: Listening on Nix Daemon Socket.198alpha # [1582727.831879] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.199alpha # [1582727.831902] alpha systemd[1]: Reached target Socket Units.200alpha # [1582727.831945] alpha systemd[1]: Reached target Basic System.201alpha # [1582727.833310] alpha systemd[1]: Starting data mesher daemon...202alpha # [1582727.834151] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...203alpha # [1582727.835361] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...204alpha # [1582727.836827] alpha systemd[1]: Starting D-Bus System Message Bus...205alpha # [1582727.856374] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.206alpha # [1582727.872210] alpha systemd-networkd[207]: eth1: Gained IPv6LL207beta # [1582727.936684] beta systemd[1]: Started Network Name Resolution.208gamma # [1582727.941316] gamma systemd-resolved[140]: Positive Trust Anchors:209beta # [1582727.936847] beta systemd[1]: Reached target Network.210beta # [1582727.936969] beta systemd[1]: Reached target System Initialization.211gamma # [1582727.941329] gamma systemd-resolved[140]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d212gamma # [1582727.941333] gamma systemd-resolved[140]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16213gamma # [1582727.941367] gamma systemd-resolved[140]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test214gamma # [1582727.964031] gamma systemd-resolved[140]: Using system hostname 'gamma'.215gamma # [1582727.965500] gamma systemd[1]: Started Network Name Resolution.216gamma # [1582727.965588] gamma systemd[1]: Reached target Network.217gamma # [1582727.965657] gamma systemd[1]: Reached target System Initialization.218gamma # [1582727.965710] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container219gamma # [1582727.965738] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.220gamma # [1582727.965756] gamma systemd[1]: Reached target Timer Units.221beta # [1582727.937084] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container222beta # [1582727.937144] beta systemd[1]: Started Daily Cleanup of Temporary Directories.223beta # [1582727.937183] beta systemd[1]: Reached target Timer Units.224beta # [1582727.937430] beta systemd[1]: Listening on D-Bus System Message Bus Socket.225beta # [1582727.937684] beta systemd[1]: Listening on Nix Daemon Socket.226beta # [1582727.937913] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.227beta # [1582727.937963] beta systemd[1]: Reached target Socket Units.228beta # [1582727.938047] beta systemd[1]: Reached target Basic System.229beta # [1582727.941662] beta systemd[1]: Starting data mesher daemon...230beta # [1582727.943268] beta systemd[1]: Starting Import lastlog data into lastlog2 database...231gamma # [1582727.965883] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.232gamma # [1582727.966011] gamma systemd[1]: Listening on Nix Daemon Socket.233gamma # [1582727.966117] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.234gamma # [1582727.966138] gamma systemd[1]: Reached target Socket Units.235beta # [1582727.944846] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...236beta # [1582727.992530] beta systemd[1]: Starting D-Bus System Message Bus...237beta # [1582728.009358] beta systemd[1]: Finished Import lastlog data into lastlog2 database.238gamma # [1582727.966175] gamma systemd[1]: Reached target Basic System.239gamma # [1582727.992579] gamma systemd[1]: Starting data mesher daemon...240gamma # [1582727.993471] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...241gamma # [1582727.994301] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...242gamma # [1582727.995579] gamma systemd[1]: Starting D-Bus System Message Bus...243gamma # [1582728.011653] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.244alpha # [1582728.337022] alpha nsncd[215]: Sep 22 15:05:48.322 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"245alpha # [1582728.336916] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).246alpha # [1582728.336999] alpha systemd[1]: Reached target Host and Network Name Lookups.247alpha # [1582728.337066] alpha systemd[1]: Reached target User and Group Name Lookups.248alpha # [1582728.338370] alpha systemd[1]: Starting User Login Management...249alpha # [1582728.339235] alpha systemd[1]: Starting Permit User Sessions...250alpha # [1582728.349717] alpha systemd[1]: Finished Permit User Sessions.251alpha # [1582728.351013] alpha systemd[1]: Started Console Getty.252alpha # [1582728.351065] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0253alpha # [1582728.351088] alpha systemd[1]: Reached target Login Prompts.254beta # [1582728.459127] beta systemd[1]: Started Name Service Cache Daemon (nsncd).255beta # [1582728.459195] beta systemd[1]: Reached target Host and Network Name Lookups.256beta # [1582728.459255] beta systemd[1]: Reached target User and Group Name Lookups.257beta # [1582728.460395] beta systemd[1]: Starting User Login Management...258beta # [1582728.461280] beta systemd[1]: Starting Permit User Sessions...259beta # [1582728.464140] beta nsncd[215]: Sep 22 15:05:48.444 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"260beta # [1582728.511449] beta systemd[1]: Finished Permit User Sessions.261beta # [1582728.512494] beta systemd[1]: Started Console Getty.262beta # [1582728.512532] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0263beta # [1582728.512551] beta systemd[1]: Reached target Login Prompts.264gamma # [1582728.448174] gamma systemd-networkd[207]: eth1: Gained IPv6LL265gamma # [1582728.544943] gamma nsncd[215]: Sep 22 15:05:48.530 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"266gamma # [1582728.545007] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).267gamma # [1582728.545084] gamma systemd[1]: Reached target Host and Network Name Lookups.268gamma # [1582728.545156] gamma systemd[1]: Reached target User and Group Name Lookups.269gamma # [1582728.546436] gamma systemd[1]: Starting User Login Management...270gamma # [1582728.547361] gamma systemd[1]: Starting Permit User Sessions...271gamma # [1582728.557545] gamma systemd[1]: Finished Permit User Sessions.272gamma # [1582728.558486] gamma systemd[1]: Started Console Getty.273gamma # [1582728.558521] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0274gamma # [1582728.558538] gamma systemd[1]: Reached target Login Prompts.275gamma # [1582729.094393] gamma dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...276beta # [1582729.138551] beta dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...277beta # [1582729.139411] beta dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'278beta # [1582729.139411] beta dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"279alpha # [1582729.026075] alpha dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...280gamma # [1582729.095210] gamma dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'281alpha # [1582729.026996] alpha dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'282gamma # [1582729.095210] gamma dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"283beta # [1582729.139867] beta systemd[1]: Started D-Bus System Message Bus.284alpha # [1582729.026996] alpha dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"285gamma # [1582729.095622] gamma systemd[1]: Started D-Bus System Message Bus.286alpha # [1582729.031361] alpha systemd[1]: Started D-Bus System Message Bus.287alpha # [1582729.038922] alpha dbus-broker-launch[216]: Ready288gamma # [1582729.102620] gamma dbus-broker-launch[216]: Ready289beta # [1582729.148025] beta dbus-broker-launch[216]: Ready290gamma # [1582731.577093] gamma data-mesher[213]: time=2026-09-22T15:05:51.562Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]291gamma # [1582731.578325] gamma data-mesher[213]: time=2026-09-22T15:05:51.564Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC292gamma # [1582731.578325] gamma data-mesher[213]: time=2026-09-22T15:05:51.564Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml293gamma # [1582731.637675] gamma data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="checking file integrity"294gamma # [1582731.637845] gamma data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="file integrity check complete"295gamma # [1582731.642198] gamma data-mesher[213]: time=2026-09-22T15:05:51.627Z level=INFO msg="libp2p host created" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.3/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::3/tcp/7946]"296gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=GET path=/files297gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name298gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name299gamma # [1582731.642294] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="starting server"300gamma # [1582731.642491] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="waiting for DHT to populate" delay=10s301gamma # [1582731.642491] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=[::1]:7331302gamma # [1582731.642491] gamma data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331303gamma # [1582731.646495] gamma data-mesher[213]: time=2026-09-22T15:05:51.632Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip6/2001:db8:1::1/tcp/7946304gamma # [1582731.652363] gamma data-mesher[213]: time=2026-09-22T15:05:51.638Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/7946305gamma # [1582731.656421] gamma data-mesher[213]: time=2026-09-22T15:05:51.642Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip4/192.168.1.2/tcp/7946306gamma # [1582731.656421] gamma data-mesher[213]: time=2026-09-22T15:05:51.642Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/49848307alpha # [1582731.592399] alpha data-mesher[213]: time=2026-09-22T15:05:51.578Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]308alpha # [1582731.593427] alpha data-mesher[213]: time=2026-09-22T15:05:51.579Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg309alpha # [1582731.593507] alpha data-mesher[213]: time=2026-09-22T15:05:51.579Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml310alpha # [1582731.637931] alpha data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="checking file integrity"311alpha # [1582731.638047] alpha data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="file integrity check complete"312alpha # [1582731.642230] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="libp2p host created" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.1/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::1/tcp/7946]"313alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=GET path=/files314alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name315alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name316alpha # [1582731.642287] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="starting server"317alpha # [1582731.642373] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="waiting for DHT to populate" delay=10s318alpha # [1582731.642466] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=[::1]:7331319alpha # [1582731.642492] alpha data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331320alpha # [1582731.647306] alpha data-mesher[213]: time=2026-09-22T15:05:51.633Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip6/2001:db8:1::3/tcp/7946321alpha # [1582731.647724] alpha data-mesher[213]: time=2026-09-22T15:05:51.633Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/7946322alpha # [1582731.648864] alpha data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip6/2001:db8:1::2/tcp/57818323beta # [1582731.610816] beta data-mesher[213]: time=2026-09-22T15:05:51.596Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]324beta # [1582731.612374] beta data-mesher[213]: time=2026-09-22T15:05:51.598Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC325beta # [1582731.612425] beta data-mesher[213]: time=2026-09-22T15:05:51.598Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml326beta # [1582731.637943] beta data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="checking file integrity"327beta # [1582731.638092] beta data-mesher[213]: time=2026-09-22T15:05:51.623Z level=INFO msg="file integrity check complete"328beta # [1582731.642198] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="libp2p host created" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946]"329beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=GET path=/files330beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name331beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name332beta # [1582731.642237] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="starting server"333beta # [1582731.642348] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="waiting for DHT to populate" delay=10s334beta # [1582731.642460] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=[::1]:7331335beta # [1582731.642486] beta data-mesher[213]: time=2026-09-22T15:05:51.628Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331336beta # [1582731.648158] beta data-mesher[213]: time=2026-09-22T15:05:51.633Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip6/2001:db8:1::1/tcp/7946337beta # [1582731.648823] beta data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946338beta # [1582731.649047] beta data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer disconnected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946339beta # [1582731.649112] beta data-mesher[213]: time=2026-09-22T15:05:51.634Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip6/2001:db8:1::1/tcp/7946340beta # [1582731.653001] beta data-mesher[213]: time=2026-09-22T15:05:51.638Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip6/2001:db8:1::3/tcp/7946341beta # [1582731.655687] beta data-mesher[213]: time=2026-09-22T15:05:51.641Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946342beta # [1582731.655753] beta data-mesher[213]: time=2026-09-22T15:05:51.641Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip6/2001:db8:1::3/tcp/7946343beta # [1582731.950240] beta systemd-logind[232]: New seat seat0.344beta # [1582731.950489] beta systemd[1]: Started User Login Management.345beta # [1582731.952941] beta systemd[1]: Starting linger-users.service...346beta # [1582732.004301] beta systemd[1]: linger-users.service: Deactivated successfully.347beta # [1582732.004479] beta systemd[1]: Finished linger-users.service.348alpha # [1582731.880830] alpha systemd-logind[232]: New seat seat0.349alpha # [1582731.881070] alpha systemd[1]: Started User Login Management.350alpha # [1582731.882727] alpha systemd[1]: Starting linger-users.service...351alpha # [1582731.895331] alpha systemd[1]: linger-users.service: Deactivated successfully.352alpha # [1582731.895577] alpha systemd[1]: Finished linger-users.service.353gamma # [1582732.008145] gamma systemd-logind[232]: New seat seat0.354gamma # [1582732.009806] gamma systemd[1]: Started User Login Management.355gamma # [1582732.011745] gamma systemd[1]: Starting linger-users.service...356gamma # [1582732.027798] gamma systemd[1]: linger-users.service: Deactivated successfully.357gamma # [1582732.027983] gamma systemd[1]: Finished linger-users.service.358alpha: still waiting for container 'alpha' to reach ready state...359alpha: (finished: waiting for unit data-mesher.service, in 18.16 seconds)360beta: waiting for unit data-mesher.service361beta: (finished: waiting for unit data-mesher.service, in 0.01 seconds)362gamma: waiting for unit data-mesher.service363gamma: (finished: waiting for unit data-mesher.service, in 0.01 seconds)364alpha: must succeed: echo -n 'hello world' > /tmp/test_file365alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)366alpha: 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.key367alpha # [1582741.642638] alpha data-mesher[213]: time=2026-09-22T15:06:01.628Z level=INFO msg="performing state exchange with peers on join" count=1368alpha # [1582741.642638] alpha data-mesher[213]: time=2026-09-22T15:06:01.628Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s369alpha # [1582741.643592] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC370alpha # [1582741.643592] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s371alpha # [1582741.643730] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="server started"372alpha # [1582741.643730] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="starting expired-file sweeper" interval=1m0s373alpha # [1582741.643876] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC374alpha # [1582741.643876] alpha data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC375alpha # [1582741.643916] alpha systemd[1]: Started data mesher daemon.376alpha # [1582741.644211] alpha systemd[1]: Reached target Multi-User System.377alpha # [1582741.648273] alpha systemd[1]: Startup finished in 17.351s.378alpha # [1582741.913955] alpha data-mesher[213]: time=2026-09-22T15:06:01.899Z level=INFO msg=http_request uri=/files/test_file status=204379alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)380??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.381 File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39382alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file383??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.384 File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39385alpha: (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)386beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file387gamma # [1582741.643023] gamma data-mesher[213]: time=2026-09-22T15:06:01.628Z level=INFO msg="performing state exchange with peers on join" count=1388gamma # [1582741.643023] gamma data-mesher[213]: time=2026-09-22T15:06:01.628Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s389gamma # [1582741.643862] gamma data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC390gamma # [1582741.643862] gamma data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC391gamma # [1582741.644318] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg392gamma # [1582741.644318] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s393gamma # [1582741.644414] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="server started"394gamma # [1582741.644447] gamma data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="starting expired-file sweeper" interval=1m0s395gamma # [1582741.644978] gamma systemd[1]: Started data mesher daemon.396gamma # [1582741.645499] gamma systemd[1]: Reached target Multi-User System.397gamma # [1582741.648285] gamma systemd[1]: Startup finished in 17.306s.398beta # [1582741.642473] beta data-mesher[213]: time=2026-09-22T15:06:01.628Z level=INFO msg="performing state exchange with peers on join" count=1399beta # [1582741.642886] beta data-mesher[213]: time=2026-09-22T15:06:01.628Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s400beta # [1582741.643254] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg401beta # [1582741.643291] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg402beta # [1582741.644090] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC403beta # [1582741.644090] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s404beta # [1582741.644143] beta data-mesher[213]: time=2026-09-22T15:06:01.629Z level=INFO msg="server started"405beta # [1582741.644224] beta data-mesher[213]: time=2026-09-22T15:06:01.630Z level=INFO msg="starting expired-file sweeper" interval=1m0s406beta # [1582741.644324] beta systemd[1]: Started data mesher daemon.407beta # [1582741.644566] beta systemd[1]: Reached target Multi-User System.408beta # [1582741.648291] beta systemd[1]: Startup finished in 17.346s.409gamma # [1582746.645185] gamma data-mesher[213]: time=2026-09-22T15:06:06.630Z level=DEBUG msg="attempting push/pull" peer_count=2410gamma # [1582746.645725] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s411beta # [1582746.645515] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg412alpha # [1582746.644138] alpha data-mesher[213]: time=2026-09-22T15:06:06.629Z level=DEBUG msg="attempting push/pull" peer_count=2413alpha # [1582746.644745] alpha data-mesher[213]: time=2026-09-22T15:06:06.629Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s414alpha # [1582746.645703] alpha data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC415alpha # [1582746.645703] alpha data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s416alpha # [1582746.645796] alpha data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="push/pull successful" interval=5s417alpha # [1582746.646291] alpha data-mesher[213]: time=2026-09-22T15:06:06.632Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file418gamma # [1582746.646014] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC419alpha # [1582746.648114] alpha data-mesher[213]: time=2026-09-22T15:06:06.633Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file420beta # [1582746.645515] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg421beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file422beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="attempting push/pull" peer_count=2423beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s424beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="scheduling file download" name=test_file425beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg426beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC427beta # [1582746.646127] beta data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC428beta # [1582746.646348] beta data-mesher[213]: time=2026-09-22T15:06:06.632Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC429beta # [1582746.646348] beta data-mesher[213]: time=2026-09-22T15:06:06.632Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s430beta # [1582746.646348] beta data-mesher[213]: time=2026-09-22T15:06:06.632Z level=DEBUG msg="push/pull successful" interval=5s431beta # [1582746.652594] beta data-mesher[213]: time=2026-09-22T15:06:06.638Z level=INFO msg="download complete" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=6.952056ms432gamma # [1582746.646014] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s433gamma # [1582746.646112] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC434gamma # [1582746.646112] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC435gamma # [1582746.646112] gamma data-mesher[213]: time=2026-09-22T15:06:06.631Z level=DEBUG msg="push/pull successful" interval=5s436beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)437gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file438gamma # [1582751.646469] gamma data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="attempting push/pull" peer_count=2439gamma # [1582751.646469] gamma data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s440gamma # [1582751.647237] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC441gamma # [1582751.647282] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="new file detected" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file name=test_file442gamma # [1582751.647282] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s443beta # [1582751.646410] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="attempting push/pull" peer_count=2444gamma # [1582751.647282] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="push/pull successful" interval=5s445beta # [1582751.646798] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s446gamma # [1582751.647464] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="scheduling file download" name=test_file447gamma # [1582751.647538] gamma data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC448alpha # [1582751.646555] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="attempting push/pull" peer_count=2449beta # [1582751.646966] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC450alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s451gamma # [1582751.662473] gamma data-mesher[213]: time=2026-09-22T15:06:11.648Z level=INFO msg="download complete" name=test_file signed_at="2026-09-22 15:06:01.896 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC written=true elapsed=14.960165ms452alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC453beta # [1582751.646966] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC454alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC455alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC456alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s457alpha # [1582751.647298] alpha data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="push/pull successful" interval=5s458beta # [1582751.647079] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg459beta # [1582751.647079] beta data-mesher[213]: time=2026-09-22T15:06:11.632Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg460beta # [1582751.647183] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg461beta # [1582751.647210] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s462beta # [1582751.647233] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=DEBUG msg="push/pull successful" interval=5s463beta # [1582751.647919] beta data-mesher[213]: time=2026-09-22T15:06:11.633Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file464beta # [1582751.649063] beta data-mesher[213]: time=2026-09-22T15:06:11.634Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file465gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)466beta: 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.key467beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.02 seconds)468alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file469beta # [1582752.029796] beta data-mesher[213]: time=2026-09-22T15:06:12.015Z level=INFO msg=http_request uri=/files/test_file status=204470beta # [1582756.647409] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="attempting push/pull" peer_count=2471beta # [1582756.647409] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s472beta # [1582756.648074] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC473beta # [1582756.648074] beta data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC474beta # [1582756.649956] beta data-mesher[213]: time=2026-09-22T15:06:16.635Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg475beta # [1582756.650000] beta data-mesher[213]: time=2026-09-22T15:06:16.635Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s476beta # [1582756.650058] beta data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="push/pull successful" interval=5s477alpha # [1582756.647436] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="attempting push/pull" peer_count=2478alpha # [1582756.647436] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s479alpha # [1582756.648353] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC480alpha # [1582756.648353] alpha data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC481alpha # [1582756.648530] alpha data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC482alpha # [1582756.648608] alpha data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s483alpha # [1582756.648608] alpha data-mesher[213]: time=2026-09-22T15:06:16.634Z level=DEBUG msg="push/pull successful" interval=5s484alpha # [1582756.649599] alpha data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true485gamma # [1582756.647409] gamma data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="attempting push/pull" peer_count=2486gamma # [1582756.647921] gamma data-mesher[213]: time=2026-09-22T15:06:16.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s487gamma # [1582756.648162] gamma data-mesher[213]: time=2026-09-22T15:06:16.633Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg488gamma # [1582756.648214] gamma data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg489gamma # [1582756.648325] gamma data-mesher[213]: time=2026-09-22T15:06:16.634Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC490gamma # [1582756.649268] gamma data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true491gamma # [1582756.649268] gamma data-mesher[213]: time=2026-09-22T15:06:16.635Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s492gamma # [1582756.649332] gamma data-mesher[213]: time=2026-09-22T15:06:16.635Z level=DEBUG msg="push/pull successful" interval=5s493alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)494beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file495beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)496gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file497gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)498alpha: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub499alpha: (finished: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)500alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file501alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)502alpha: 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.cert503alpha: (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)504alpha: 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-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE505alpha: (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)506beta: 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-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE507alpha # [1582757.133108] alpha data-mesher[213]: time=2026-09-22T15:06:17.118Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=204508beta # [1582761.649803] beta data-mesher[213]: time=2026-09-22T15:06:21.635Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg509beta # [1582761.649803] beta data-mesher[213]: time=2026-09-22T15:06:21.635Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg510beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.635Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE511beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="attempting push/pull" peer_count=2512beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s513beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE514beta # [1582761.650386] beta data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg515beta # [1582761.651664] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg516beta # [1582761.651664] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE517beta # [1582761.651664] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s518beta # [1582761.651910] beta data-mesher[213]: time=2026-09-22T15:06:21.637Z level=DEBUG msg="push/pull successful" interval=5s519beta # [1582761.652596] beta data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=2.290032ms520alpha # [1582761.649086] alpha data-mesher[213]: time=2026-09-22T15:06:21.634Z level=DEBUG msg="attempting push/pull" peer_count=2521alpha # [1582761.649086] alpha data-mesher[213]: time=2026-09-22T15:06:21.634Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s522alpha # [1582761.650445] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC523alpha # [1582761.650536] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s524alpha # [1582761.650536] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="push/pull successful" interval=5s525alpha # [1582761.650741] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC526alpha # [1582761.650741] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE527alpha # [1582761.651123] alpha data-mesher[213]: time=2026-09-22T15:06:21.636Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC528alpha # [1582761.651572] alpha data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC529alpha # [1582761.651572] alpha data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC530alpha # [1582761.651725] alpha data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE531alpha # [1582761.652841] alpha data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE532alpha # [1582761.654749] alpha data-mesher[213]: time=2026-09-22T15:06:21.640Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE533gamma # [1582761.650436] gamma data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="attempting push/pull" peer_count=2534gamma # [1582761.650436] gamma data-mesher[213]: time=2026-09-22T15:06:21.636Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s535gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.637Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg536gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE537gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s538gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=DEBUG msg="push/pull successful" interval=5s539gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE540gamma # [1582761.652479] gamma data-mesher[213]: time=2026-09-22T15:06:21.638Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg541gamma # [1582761.656082] gamma data-mesher[213]: time=2026-09-22T15:06:21.641Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 15:06:17.117 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=3.61049ms542beta: (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)543gamma: 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-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE544gamma: (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)545alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert546alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)547alpha: 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.pub548Error: failed to update file: 403 Forbidden, signer Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE= is not authorized for this file test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE549alpha: (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)550(finished: run the VM test script, in 38.55 seconds)551alpha # [1582762.238460] alpha data-mesher[213]: time=2026-09-22T15:06:22.224Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=403552test script finished in 39.12s553cleanup554kill NspawnMachine (pid 54)555kill NspawnMachine (pid 53)556kill NspawnMachine (pid 55)557Container alpha terminated by signal KILL.558Container beta terminated by signal KILL.559(finished: cleanup, in 0.44 seconds)560Container gamma terminated by signal KILL.

succeeded container-test-run-data-mesher checks.aarch64-linux.data-mesher · build #756 · raw

succeeded `container-test-run-data-mesher` `checks.aarch64-linux.data-mesher` · build #756 · raw