container-test-run-data-mesher
checks.aarch64-linux.data-mesher
· build #1046
· raw
1build01: building rj0v9zsbbi007x36k2446pgjgsr3w1n1-container-test-run-data-mesher.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Initializing machine ID from random generator.6Test will time out and terminate in 3600.0 seconds7run the VM test script8additionally exposed symbols:9 alpha, beta, gamma,10 vlan1,11 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_ssh12start all VMs13alpha: systemd-nspawn running (pid 52)14beta: systemd-nspawn running (pid 54)15gamma: systemd-nspawn running (pid 55)16alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...17beta: Waiting for journal at /build/vm-state-beta/var/log/journal...18gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...19(finished: start all VMs, in 0.00 seconds)20alpha: waiting for unit data-mesher.service21nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE22nixos-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.23nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE24nixos-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.25nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE26nixos-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.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.29Note: 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.30░ Spawning container alpha on /build/vm-state-alpha.31░ Spawning container beta on /build/vm-state-beta.32░ Spawning container gamma on /build/vm-state-gamma.33beta # No journal files were found.34beta # No journal boot entry found for the specified boot (+0).35gamma # No journal boot entry found for the specified boot (+0).36alpha # [348138.745523] alpha systemd-journald[90]: Journal started37alpha # [348138.745581] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/d427d1e2d73a4ba183a25a33aed38d4f) is 8M, max 2.5G, 2.4G free.38alpha # [348138.757099] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.39alpha # [348138.769871] alpha systemd[1]: Starting Flush Journal to Persistent Storage...40alpha # [348138.772632] alpha systemd[1]: Starting Network Name Resolution...41alpha # [348138.773352] alpha systemd[1]: Starting Create Static Device Nodes in /dev...42alpha # [348138.781539] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/d427d1e2d73a4ba183a25a33aed38d4f is 1.575ms for 6 entries.43alpha # [348138.781539] alpha systemd-journald[90]: System Journal (/var/log/journal/d427d1e2d73a4ba183a25a33aed38d4f) is 8M, max 4G, 3.9G free.44alpha # [348138.799227] alpha systemd[1]: Finished Flush Journal to Persistent Storage.45alpha # [348138.799759] alpha systemd[1]: Finished Create Static Device Nodes in /dev.46alpha # [348138.800896] alpha systemd[1]: Reached target Preparation for Local File Systems.47alpha # [348138.801008] alpha systemd[1]: Reached target Local File Systems.48alpha # [348138.801716] alpha systemd[1]: Listening on Boot Loader Control Service Socket.49alpha # [348138.801764] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container50alpha # [348138.802686] alpha systemd[1]: Starting Save Transient machine-id to Disk...51alpha # [348138.803384] alpha systemd[1]: Starting Create System Files and Directories...52alpha # [348138.803419] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys53alpha # [348138.820148] alpha systemd-tmpfiles[142]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted54alpha # [348138.820333] alpha systemd-tmpfiles[142]: fchmod() of /var/log/journal failed: Operation not permitted55alpha # [348138.820469] alpha systemd-tmpfiles[142]: fchmod() of /var/log/journal/d427d1e2d73a4ba183a25a33aed38d4f failed: Operation not permitted56alpha # [348138.820672] alpha systemd-tmpfiles[142]: fchmod() of /run/log/journal failed: Operation not permitted57alpha # [348138.822216] alpha systemd[1]: Finished Create System Files and Directories.58alpha # [348138.823382] alpha systemd[1]: Starting Rebuild Journal Catalog...59alpha # [348138.824250] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...60alpha # [348138.838935] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.61alpha # [348138.847940] alpha systemd[1]: Finished Rebuild Journal Catalog.62alpha # [348138.849286] alpha systemd[1]: Starting Update is Completed...63alpha # [348138.861859] alpha systemd[1]: Finished Update is Completed.64alpha # [348138.885434] alpha systemd[1]: Finished Save Transient machine-id to Disk.65alpha # [348138.925700] alpha systemd[1]: Finished Firewall.66alpha # [348138.925868] alpha systemd[1]: Reached target Preparation for Network.67alpha # [348138.926129] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.68alpha # [348138.927283] alpha systemd[1]: Starting Network Management...69beta # [348138.772713] beta systemd-journald[91]: Journal started70beta # [348138.772765] beta systemd-journald[91]: Runtime Journal (/run/log/journal/1d3ff78a7b7d4c49b60d24fd6d552d80) is 8M, max 2.5G, 2.4G free.71beta # [348138.799421] beta systemd[1]: Finished Apply Kernel Variables.72beta # [348138.817218] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.73beta # [348138.837303] beta systemd[1]: Starting Flush Journal to Persistent Storage...74beta # [348138.838243] beta systemd[1]: Starting Network Name Resolution...75beta # [348138.838975] beta systemd[1]: Starting Create Static Device Nodes in /dev...76beta # [348138.849091] beta systemd-journald[91]: Time spent on flushing to /var/log/journal/1d3ff78a7b7d4c49b60d24fd6d552d80 is 2.174ms for 7 entries.77beta # [348138.849091] beta systemd-journald[91]: System Journal (/var/log/journal/1d3ff78a7b7d4c49b60d24fd6d552d80) is 8M, max 4G, 3.9G free.78beta # [348138.858193] beta systemd[1]: Finished Create Static Device Nodes in /dev.79beta # [348138.858867] beta systemd[1]: Reached target Preparation for Local File Systems.80beta # [348138.859022] beta systemd[1]: Reached target Local File Systems.81beta # [348138.859875] beta systemd[1]: Listening on Boot Loader Control Service Socket.82beta # [348138.859939] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container83beta # [348138.860806] beta systemd[1]: Starting Save Transient machine-id to Disk...84beta # [348138.860850] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys85beta # [348138.866911] beta systemd[1]: Finished Flush Journal to Persistent Storage.86beta # [348138.868538] beta systemd[1]: Starting Create System Files and Directories...87beta # [348138.885510] beta systemd[1]: Finished Save Transient machine-id to Disk.88beta # [348138.888090] beta systemd-tmpfiles[158]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted89beta # [348138.888253] beta systemd-tmpfiles[158]: fchmod() of /var/log/journal failed: Operation not permitted90beta # [348138.888358] beta systemd-tmpfiles[158]: fchmod() of /var/log/journal/1d3ff78a7b7d4c49b60d24fd6d552d80 failed: Operation not permitted91beta # [348138.888509] beta systemd-tmpfiles[158]: fchmod() of /run/log/journal failed: Operation not permitted92beta # [348138.889888] beta systemd[1]: Finished Create System Files and Directories.93beta # [348138.891167] beta systemd[1]: Starting Rebuild Journal Catalog...94beta # [348138.891906] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...95beta # [348138.904747] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.96gamma # [348138.779294] gamma systemd-journald[90]: Journal started97beta # [348138.914730] beta systemd[1]: Finished Rebuild Journal Catalog.98gamma # [348138.779354] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/52b176cc8c414d20914a1519d5e1073a) is 8M, max 2.5G, 2.4G free.99beta # [348138.915793] beta systemd[1]: Starting Update is Completed...100gamma # [348138.785163] gamma systemd[1]: Finished Apply Kernel Variables.101beta # [348138.928063] beta systemd[1]: Finished Update is Completed.102gamma # [348138.800535] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.103beta # [348138.949089] beta systemd[1]: Finished Firewall.104gamma # [348138.815314] gamma systemd[1]: Starting Flush Journal to Persistent Storage...105beta # [348138.949281] beta systemd[1]: Reached target Preparation for Network.106gamma # [348138.816285] gamma systemd[1]: Starting Network Name Resolution...107beta # [348138.949571] beta systemd[1]: Listening on Network Management Resolve Hook Socket.108gamma # [348138.817009] gamma systemd[1]: Starting Create Static Device Nodes in /dev...109beta # [348138.950820] beta systemd[1]: Starting Network Management...110gamma # [348138.826902] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/52b176cc8c414d20914a1519d5e1073a is 1.647ms for 7 entries.111beta # [348139.667423] beta systemd-networkd[209]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted112gamma # [348138.826902] gamma systemd-journald[90]: System Journal (/var/log/journal/52b176cc8c414d20914a1519d5e1073a) is 8M, max 4G, 3.9G free.113beta # [348139.667518] beta systemd-networkd[209]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted114gamma # [348138.839633] gamma systemd[1]: Finished Create Static Device Nodes in /dev.115beta # [348139.677543] beta systemd-networkd[209]: /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.116gamma # [348138.840242] gamma systemd[1]: Reached target Preparation for Local File Systems.117beta # [348139.677703] beta systemd-networkd[209]: /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.118gamma # [348138.840368] gamma systemd[1]: Reached target Local File Systems.119beta # [348139.677848] beta systemd-networkd[209]: lo: Link UP120gamma # [348138.841149] gamma systemd[1]: Listening on Boot Loader Control Service Socket.121beta # [348139.677853] beta systemd-networkd[209]: lo: Gained carrier122gamma # [348138.841201] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container123beta # [348139.678038] beta systemd-networkd[209]: eth1: Configuring with /etc/systemd/network/40-eth1.network.124gamma # [348138.841986] gamma systemd[1]: Starting Save Transient machine-id to Disk...125beta # [348139.678485] beta systemd-networkd[209]: eth1: Link UP126gamma # [348138.842023] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys127beta # [348139.678682] beta systemd[1]: Started Network Management.128gamma # [348138.852856] gamma systemd[1]: Finished Flush Journal to Persistent Storage.129beta # [348139.678695] beta systemd-networkd[209]: eth1: Gained carrier130gamma # [348138.854373] gamma systemd[1]: Starting Create System Files and Directories...131beta # [348139.680305] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...132gamma # [348138.873525] gamma systemd-tmpfiles[149]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted133beta # [348139.718314] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.134gamma # [348138.873711] gamma systemd-tmpfiles[149]: fchmod() of /var/log/journal failed: Operation not permitted135beta # [348139.785438] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.136gamma # [348138.873835] gamma systemd-tmpfiles[149]: fchmod() of /var/log/journal/52b176cc8c414d20914a1519d5e1073a failed: Operation not permitted137gamma # [348138.874026] gamma systemd-tmpfiles[149]: fchmod() of /run/log/journal failed: Operation not permitted138gamma # [348138.877365] gamma systemd[1]: Finished Create System Files and Directories.139gamma # [348138.878887] gamma systemd[1]: Starting Rebuild Journal Catalog...140gamma # [348138.879944] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...141gamma # [348138.885325] gamma systemd[1]: Finished Save Transient machine-id to Disk.142gamma # [348138.894351] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.143gamma # [348138.904166] gamma systemd[1]: Finished Rebuild Journal Catalog.144gamma # [348138.905269] gamma systemd[1]: Starting Update is Completed...145gamma # [348138.916015] gamma systemd[1]: Finished Update is Completed.146gamma # [348138.955279] gamma systemd[1]: Finished Firewall.147gamma # [348138.955458] gamma systemd[1]: Reached target Preparation for Network.148gamma # [348138.955717] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.149gamma # [348138.956855] gamma systemd[1]: Starting Network Management...150gamma # [348139.625521] gamma systemd-networkd[208]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted151gamma # [348139.625607] gamma systemd-networkd[208]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted152gamma # [348139.636757] gamma 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.153gamma # [348139.636921] gamma 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.154gamma # [348139.637063] gamma systemd-networkd[208]: lo: Link UP155gamma # [348139.637068] gamma systemd-networkd[208]: lo: Gained carrier156gamma # [348139.637230] gamma systemd-networkd[208]: eth1: Configuring with /etc/systemd/network/40-eth1.network.157gamma # [348139.637666] gamma systemd-networkd[208]: eth1: Link UP158gamma # [348139.637704] gamma systemd[1]: Started Network Management.159gamma # [348139.637961] gamma systemd-networkd[208]: eth1: Gained carrier160gamma # [348139.638823] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...161gamma # [348139.669814] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.162gamma # [348139.769995] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.163alpha # [348139.590620] alpha systemd-networkd[208]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted164alpha # [348139.590710] alpha systemd-networkd[208]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted165alpha # [348139.599682] 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.166alpha # [348139.599844] 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.167alpha # [348139.599996] alpha systemd-networkd[208]: lo: Link UP168alpha # [348139.600012] alpha systemd-networkd[208]: lo: Gained carrier169alpha # [348139.600192] alpha systemd-networkd[208]: eth1: Configuring with /etc/systemd/network/40-eth1.network.170alpha # [348139.600674] alpha systemd-networkd[208]: eth1: Link UP171alpha # [348139.600913] alpha systemd[1]: Started Network Management.172alpha # [348139.600992] alpha systemd-networkd[208]: eth1: Gained carrier173alpha # [348139.602806] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...174alpha # [348139.634072] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.175alpha # [348139.737540] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.176alpha # [348139.947585] alpha systemd-resolved[119]: Positive Trust Anchors:177alpha # [348139.947602] alpha systemd-resolved[119]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d178alpha # [348139.947605] alpha systemd-resolved[119]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16179alpha # [348139.947639] alpha systemd-resolved[119]: 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 test180alpha # [348139.948383] alpha systemd-resolved[119]: Using system hostname 'alpha'.181alpha # [348139.949934] alpha systemd[1]: Started Network Name Resolution.182alpha # [348139.950026] alpha systemd[1]: Reached target Network.183alpha # [348139.950109] alpha systemd[1]: Reached target System Initialization.184alpha # [348139.950163] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container185alpha # [348139.950193] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.186alpha # [348139.950215] alpha systemd[1]: Reached target Timer Units.187alpha # [348139.950350] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.188alpha # [348139.950482] alpha systemd[1]: Listening on Nix Daemon Socket.189alpha # [348139.950601] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.190alpha # [348139.950629] alpha systemd[1]: Reached target Socket Units.191alpha # [348139.950672] alpha systemd[1]: Reached target Basic System.192alpha # [348139.952041] alpha systemd[1]: Starting data mesher daemon...193alpha # [348139.952908] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...194alpha # [348139.953977] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...195alpha # [348139.955557] alpha systemd[1]: Starting D-Bus System Message Bus...196alpha # [348139.973277] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.197alpha # [348140.181483] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).198alpha # [348140.181601] alpha systemd[1]: Reached target Host and Network Name Lookups.199alpha # [348140.181721] alpha systemd[1]: Reached target User and Group Name Lookups.200alpha # [348140.181846] alpha nsncd[215]: Oct 20 10:05:45.153 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"201alpha # [348140.184368] alpha systemd[1]: Starting User Login Management...202alpha # [348140.185755] alpha systemd[1]: Starting Permit User Sessions...203gamma # [348140.036188] gamma systemd-resolved[124]: Positive Trust Anchors:204gamma # [348140.036204] gamma systemd-resolved[124]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d205gamma # [348140.036208] gamma systemd-resolved[124]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16206gamma # [348140.036244] gamma systemd-resolved[124]: 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 test207gamma # [348140.037012] gamma systemd-resolved[124]: Using system hostname 'gamma'.208gamma # [348140.038481] gamma systemd[1]: Started Network Name Resolution.209gamma # [348140.038562] gamma systemd[1]: Reached target Network.210gamma # [348140.038638] gamma systemd[1]: Reached target System Initialization.211gamma # [348140.038685] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container212gamma # [348140.038713] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.213gamma # [348140.038730] gamma systemd[1]: Reached target Timer Units.214gamma # [348140.038860] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.215gamma # [348140.038984] gamma systemd[1]: Listening on Nix Daemon Socket.216gamma # [348140.039096] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.217gamma # [348140.039120] gamma systemd[1]: Reached target Socket Units.218gamma # [348140.039159] gamma systemd[1]: Reached target Basic System.219gamma # [348140.040590] gamma systemd[1]: Starting data mesher daemon...220gamma # [348140.041467] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...221gamma # [348140.042315] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...222gamma # [348140.043742] gamma systemd[1]: Starting D-Bus System Message Bus...223gamma # [348140.064857] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.224gamma # [348140.256194] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).225gamma # [348140.256323] gamma systemd[1]: Reached target Host and Network Name Lookups.226gamma # [348140.256456] gamma nsncd[215]: Oct 20 10:05:45.228 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"227gamma # [348140.256443] gamma systemd[1]: Reached target User and Group Name Lookups.228gamma # [348140.258535] gamma systemd[1]: Starting User Login Management...229gamma # [348140.260043] gamma systemd[1]: Starting Permit User Sessions...230gamma # [348140.271966] gamma systemd[1]: Finished Permit User Sessions.231gamma # [348140.273168] gamma systemd[1]: Started Console Getty.232gamma # [348140.273222] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0233gamma # [348140.273246] gamma systemd[1]: Reached target Login Prompts.234beta # [348140.070795] beta systemd-resolved[137]: Positive Trust Anchors:235beta # [348140.070812] beta systemd-resolved[137]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d236beta # [348140.070816] beta systemd-resolved[137]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16237beta # [348140.070855] beta systemd-resolved[137]: 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 test238beta # [348140.071651] beta systemd-resolved[137]: Using system hostname 'beta'.239beta # [348140.073450] beta systemd[1]: Started Network Name Resolution.240beta # [348140.073529] beta systemd[1]: Reached target Network.241beta # [348140.073603] beta systemd[1]: Reached target System Initialization.242beta # [348140.073651] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container243beta # [348140.073687] beta systemd[1]: Started Daily Cleanup of Temporary Directories.244beta # [348140.073706] beta systemd[1]: Reached target Timer Units.245beta # [348140.073838] beta systemd[1]: Listening on D-Bus System Message Bus Socket.246beta # [348140.073957] beta systemd[1]: Listening on Nix Daemon Socket.247beta # [348140.074069] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.248beta # [348140.074095] beta systemd[1]: Reached target Socket Units.249beta # [348140.074138] beta systemd[1]: Reached target Basic System.250beta # [348140.075428] beta systemd[1]: Starting data mesher daemon...251beta # [348140.076421] beta systemd[1]: Starting Import lastlog data into lastlog2 database...252beta # [348140.077321] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...253beta # [348140.078599] beta systemd[1]: Starting D-Bus System Message Bus...254beta # [348140.098295] beta systemd[1]: Finished Import lastlog data into lastlog2 database.255beta # [348140.290900] beta nsncd[216]: Oct 20 10:05:45.262 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"256beta # [348140.290937] beta systemd[1]: Started Name Service Cache Daemon (nsncd).257beta # [348140.291043] beta systemd[1]: Reached target Host and Network Name Lookups.258beta # [348140.291160] beta systemd[1]: Reached target User and Group Name Lookups.259beta # [348140.293421] beta systemd[1]: Starting User Login Management...260beta # [348140.294840] beta systemd[1]: Starting Permit User Sessions...261beta # [348140.307300] beta systemd[1]: Finished Permit User Sessions.262beta # [348140.309157] beta systemd[1]: Started Console Getty.263beta # [348140.309238] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0264beta # [348140.309284] beta systemd[1]: Reached target Login Prompts.265alpha # [348140.232884] alpha systemd[1]: Finished Permit User Sessions.266alpha # [348140.234711] alpha systemd[1]: Started Console Getty.267alpha # [348140.234784] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0268alpha # [348140.234824] alpha systemd[1]: Reached target Login Prompts.269alpha # [348140.410122] alpha dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...270alpha # [348140.411407] alpha dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'271alpha # [348140.411407] alpha dbus-broker-launch[216]: Invalid user-name in /nix/store/qic6slwgfpkp2jbwdzjhcgvrvp5njz2p-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"272alpha # [348140.411936] alpha systemd[1]: Started D-Bus System Message Bus.273alpha # [348140.420497] alpha dbus-broker-launch[216]: Ready274gamma # [348140.473180] gamma dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...275gamma # [348140.474235] gamma dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'276gamma # [348140.474235] gamma dbus-broker-launch[216]: Invalid user-name in /nix/store/qic6slwgfpkp2jbwdzjhcgvrvp5njz2p-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"277gamma # [348140.474807] gamma systemd[1]: Started D-Bus System Message Bus.278gamma # [348140.482779] gamma dbus-broker-launch[216]: Ready279beta # [348140.436129] beta dbus-broker-launch[217]: Looking up NSS user entry for 'systemd-timesync'...280beta # [348140.436909] beta dbus-broker-launch[217]: NSS returned no entry for 'systemd-timesync'281beta # [348140.436909] beta dbus-broker-launch[217]: Invalid user-name in /nix/store/qic6slwgfpkp2jbwdzjhcgvrvp5njz2p-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"282beta # [348140.437618] beta systemd[1]: Started D-Bus System Message Bus.283beta # [348140.445672] beta dbus-broker-launch[217]: Ready284alpha # [348140.767034] alpha data-mesher[213]: time=2026-10-20T10:05:45.738Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]285alpha # [348140.768136] alpha data-mesher[213]: time=2026-10-20T10:05:45.739Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg: [/dns/alpha.clan/tcp/7946]} {12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg286alpha # [348140.768136] alpha data-mesher[213]: time=2026-10-20T10:05:45.740Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml287alpha # [348140.770327] alpha data-mesher[213]: time=2026-10-20T10:05:45.742Z level=INFO msg="checking file integrity"288alpha # [348140.770441] alpha data-mesher[213]: time=2026-10-20T10:05:45.742Z level=INFO msg="file integrity check complete"289alpha # [348140.774419] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="libp2p host created" peer_id=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg 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]"290alpha # [348140.774510] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name291alpha # [348140.774510] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="registered HTTP route" method=GET path=/files292alpha # [348140.774510] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name293alpha # [348140.774510] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="starting server"294alpha # [348140.774745] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="waiting for DHT to populate" delay=10s295alpha # [348140.774745] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="HTTP server listening" address=[::1]:7331296alpha # [348140.774857] alpha data-mesher[213]: time=2026-10-20T10:05:45.746Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331297alpha # [348140.901409] alpha data-mesher[213]: time=2026-10-20T10:05:45.873Z level=INFO msg="peer connected" peer_id=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV remote_addr=/ip4/192.168.1.2/tcp/7946298alpha # [348140.912845] alpha data-mesher[213]: time=2026-10-20T10:05:45.884Z level=INFO msg="peer connected" peer_id=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK remote_addr=/ip4/192.168.1.3/tcp/7946299gamma # [348140.900240] gamma data-mesher[213]: time=2026-10-20T10:05:45.872Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]300gamma # [348140.901501] gamma data-mesher[213]: time=2026-10-20T10:05:45.873Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg: [/dns/alpha.clan/tcp/7946]} {12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK301gamma # [348140.901501] gamma data-mesher[213]: time=2026-10-20T10:05:45.873Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml302gamma # [348140.902630] gamma data-mesher[213]: time=2026-10-20T10:05:45.874Z level=INFO msg="checking file integrity"303gamma # [348140.902765] gamma data-mesher[213]: time=2026-10-20T10:05:45.874Z level=INFO msg="file integrity check complete"304gamma # [348140.906927] gamma data-mesher[213]: time=2026-10-20T10:05:45.878Z level=INFO msg="libp2p host created" peer_id=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK 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]"305gamma # [348140.907011] gamma data-mesher[213]: time=2026-10-20T10:05:45.878Z level=INFO msg="registered HTTP route" method=GET path=/files306gamma # [348140.907011] gamma data-mesher[213]: time=2026-10-20T10:05:45.878Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name307gamma # [348140.907011] gamma data-mesher[213]: time=2026-10-20T10:05:45.878Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name308beta # [348140.886534] beta data-mesher[214]: time=2026-10-20T10:05:45.858Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]309gamma # [348140.907011] gamma data-mesher[213]: time=2026-10-20T10:05:45.878Z level=INFO msg="starting server"310beta # [348140.887816] beta data-mesher[214]: time=2026-10-20T10:05:45.859Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg: [/dns/alpha.clan/tcp/7946]} {12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV311gamma # [348140.907214] gamma data-mesher[213]: time=2026-10-20T10:05:45.878Z level=INFO msg="waiting for DHT to populate" delay=10s312beta # [348140.887816] beta data-mesher[214]: time=2026-10-20T10:05:45.859Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml313gamma # [348140.907214] gamma data-mesher[213]: time=2026-10-20T10:05:45.878Z level=INFO msg="HTTP server listening" address=[::1]:7331314beta # [348140.890673] beta data-mesher[214]: time=2026-10-20T10:05:45.862Z level=INFO msg="checking file integrity"315gamma # [348140.907214] gamma data-mesher[213]: time=2026-10-20T10:05:45.879Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331316beta # [348140.890802] beta data-mesher[214]: time=2026-10-20T10:05:45.862Z level=INFO msg="file integrity check complete"317gamma # [348140.911773] gamma data-mesher[213]: time=2026-10-20T10:05:45.883Z level=INFO msg="peer connected" peer_id=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg remote_addr=/ip4/192.168.1.1/tcp/7946318beta # [348140.895274] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="libp2p host created" peer_id=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV 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]"319gamma # [348140.918607] gamma data-mesher[213]: time=2026-10-20T10:05:45.890Z level=INFO msg="peer connected" peer_id=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV remote_addr=/ip4/192.168.1.2/tcp/7946320beta # [348140.895340] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="registered HTTP route" method=GET path=/files321gamma # [348141.140239] gamma systemd-logind[232]: New seat seat0.322beta # [348140.895340] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name323gamma # [348141.140555] gamma systemd[1]: Started User Login Management.324beta # [348140.895340] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name325gamma # [348141.152806] gamma systemd[1]: Starting linger-users.service...326beta # [348140.895340] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="starting server"327beta # [348140.895459] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="waiting for DHT to populate" delay=10s328beta # [348140.895547] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="HTTP server listening" address=[::1]:7331329beta # [348140.895593] beta data-mesher[214]: time=2026-10-20T10:05:45.867Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331330beta # [348140.900525] beta data-mesher[214]: time=2026-10-20T10:05:45.872Z level=INFO msg="peer connected" peer_id=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg remote_addr=/ip4/192.168.1.1/tcp/7946331beta # [348140.919710] beta data-mesher[214]: time=2026-10-20T10:05:45.891Z level=INFO msg="peer connected" peer_id=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK remote_addr=/ip4/192.168.1.3/tcp/7946332beta # [348141.135083] beta systemd-logind[233]: New seat seat0.333beta # [348141.135614] beta systemd[1]: Started User Login Management.334beta # [348141.153044] beta systemd[1]: Starting linger-users.service...335alpha # [348141.052172] alpha systemd-networkd[208]: eth1: Gained IPv6LL336alpha # [348141.121757] alpha systemd-logind[232]: New seat seat0.337alpha # [348141.122096] alpha systemd[1]: Started User Login Management.338alpha # [348141.123847] alpha systemd[1]: Starting linger-users.service...339alpha # [348141.163121] alpha systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available340alpha # [348141.163241] alpha systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available341alpha # [348141.163314] alpha systemd[1]: linger-users.service: Deactivated successfully.342alpha # [348141.163561] alpha systemd[1]: Finished linger-users.service.343beta # [348141.168497] beta systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available344beta # [348141.168682] beta systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available345beta # [348141.168747] beta systemd[1]: linger-users.service: Deactivated successfully.346beta # [348141.168992] beta systemd[1]: Finished linger-users.service.347gamma # [348141.175794] gamma systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available348gamma # [348141.175821] gamma systemd[1]: linger-users.service: Failed to read pids.max for control group /system.slice/linger-users.service, ignoring: No data available349gamma # [348141.175849] gamma systemd[1]: linger-users.service: Deactivated successfully.350gamma # [348141.175934] gamma systemd[1]: Finished linger-users.service.351gamma # [348141.248210] gamma systemd-networkd[208]: eth1: Gained IPv6LL352beta # [348141.532185] beta systemd-networkd[209]: eth1: Gained IPv6LL353alpha: still waiting for container 'alpha' to reach ready state...354alpha: (finished: waiting for unit data-mesher.service, in 13.18 seconds)355beta: waiting for unit data-mesher.service356beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)357gamma: waiting for unit data-mesher.service358gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)359alpha: must succeed: echo -n 'hello world' > /tmp/test_file360alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)361alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key362alpha # [348150.775685] alpha data-mesher[213]: time=2026-10-20T10:05:55.747Z level=INFO msg="performing state exchange with peers on join" count=1363alpha # [348150.776480] alpha data-mesher[213]: time=2026-10-20T10:05:55.747Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s364alpha # [348150.776794] alpha data-mesher[213]: time=2026-10-20T10:05:55.748Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV365alpha # [348150.776794] alpha data-mesher[213]: time=2026-10-20T10:05:55.748Z level=INFO msg="state exchange complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s366alpha # [348150.776933] alpha data-mesher[213]: time=2026-10-20T10:05:55.748Z level=INFO msg="server started"367alpha # [348150.777125] alpha data-mesher[213]: time=2026-10-20T10:05:55.748Z level=INFO msg="starting expired-file sweeper" interval=1m0s368alpha # [348150.777200] alpha systemd[1]: Started data mesher daemon.369alpha # [348150.777726] alpha systemd[1]: Reached target Multi-User System.370alpha # [348150.778060] alpha systemd[1]: Startup finished in 12.377s.371alpha # [348150.896182] alpha data-mesher[213]: time=2026-10-20T10:05:55.868Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV372alpha # [348150.896182] alpha data-mesher[213]: time=2026-10-20T10:05:55.868Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV373alpha # [348150.908145] alpha data-mesher[213]: time=2026-10-20T10:05:55.879Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK374alpha # [348150.908145] alpha data-mesher[213]: time=2026-10-20T10:05:55.880Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK375alpha # [348151.081411] alpha data-mesher[213]: time=2026-10-20T10:05:56.053Z level=INFO msg=http_request uri=/files/test_file status=204376alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)377??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.378 File "/nix/store/3cbsvnfi3n2bcbv8am285hmkrj1a9kcg-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39379alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file380??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.381 File "/nix/store/3cbsvnfi3n2bcbv8am285hmkrj1a9kcg-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39382alpha: (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)383beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file384beta # [348150.776523] beta data-mesher[214]: time=2026-10-20T10:05:55.748Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg385beta # [348150.776523] beta data-mesher[214]: time=2026-10-20T10:05:55.748Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg386beta # [348150.895575] beta data-mesher[214]: time=2026-10-20T10:05:55.867Z level=INFO msg="performing state exchange with peers on join" count=1387beta # [348150.895575] beta data-mesher[214]: time=2026-10-20T10:05:55.867Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s388beta # [348150.896454] beta data-mesher[214]: time=2026-10-20T10:05:55.868Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg389beta # [348150.896454] beta data-mesher[214]: time=2026-10-20T10:05:55.868Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s390beta # [348150.896593] beta data-mesher[214]: time=2026-10-20T10:05:55.868Z level=INFO msg="server started"391beta # [348150.896855] beta systemd[1]: Started data mesher daemon.392beta # [348150.897464] beta data-mesher[214]: time=2026-10-20T10:05:55.868Z level=INFO msg="starting expired-file sweeper" interval=1m0s393beta # [348150.897403] beta systemd[1]: Reached target Multi-User System.394beta # [348150.897764] beta systemd[1]: Startup finished in 12.509s.395gamma # [348150.907619] gamma data-mesher[213]: time=2026-10-20T10:05:55.879Z level=INFO msg="performing state exchange with peers on join" count=1396gamma # [348150.907619] gamma data-mesher[213]: time=2026-10-20T10:05:55.879Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s397gamma # [348150.908473] gamma data-mesher[213]: time=2026-10-20T10:05:55.880Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg398gamma # [348150.908473] gamma data-mesher[213]: time=2026-10-20T10:05:55.880Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s399gamma # [348150.908473] gamma data-mesher[213]: time=2026-10-20T10:05:55.880Z level=INFO msg="server started"400gamma # [348150.908626] gamma data-mesher[213]: time=2026-10-20T10:05:55.880Z level=INFO msg="starting expired-file sweeper" interval=1m0s401gamma # [348150.908762] gamma systemd[1]: Started data mesher daemon.402gamma # [348150.909273] gamma systemd[1]: Reached target Multi-User System.403gamma # [348150.909585] gamma systemd[1]: Startup finished in 12.517s.404gamma # [348155.778995] gamma data-mesher[213]: time=2026-10-20T10:06:00.750Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg405gamma # [348155.778995] gamma data-mesher[213]: time=2026-10-20T10:06:00.750Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg406gamma # [348155.779778] gamma data-mesher[213]: time=2026-10-20T10:06:00.750Z level=DEBUG msg="new file detected" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg name=test_file name=test_file407gamma # [348155.779778] gamma data-mesher[213]: time=2026-10-20T10:06:00.751Z level=INFO msg="scheduling file download" name=test_file408gamma # [348155.779778] gamma data-mesher[213]: time=2026-10-20T10:06:00.751Z level=INFO msg="downloading file" name=test_file signed_at="2026-10-20 10:05:56.049 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg409gamma # [348155.899877] gamma data-mesher[213]: time=2026-10-20T10:06:00.871Z level=INFO msg="download complete" name=test_file signed_at="2026-10-20 10:05:56.049 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg written=true elapsed=120.596249ms410gamma # [348155.899877] gamma data-mesher[213]: time=2026-10-20T10:06:00.871Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV411gamma # [348155.899877] gamma data-mesher[213]: time=2026-10-20T10:06:00.871Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV412gamma # [348155.901021] gamma data-mesher[213]: time=2026-10-20T10:06:00.872Z level=INFO msg="received file request" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_file413gamma # [348155.903915] gamma data-mesher[213]: time=2026-10-20T10:06:00.875Z level=INFO msg="file transfer complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_file414gamma # [348155.909376] gamma data-mesher[213]: time=2026-10-20T10:06:00.881Z level=DEBUG msg="attempting push/pull" peer_count=2415gamma # [348155.909462] gamma data-mesher[213]: time=2026-10-20T10:06:00.881Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s416gamma # [348155.910298] gamma data-mesher[213]: time=2026-10-20T10:06:00.882Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV417gamma # [348155.910298] gamma data-mesher[213]: time=2026-10-20T10:06:00.882Z level=INFO msg="state exchange complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s418gamma # [348155.910419] gamma data-mesher[213]: time=2026-10-20T10:06:00.882Z level=DEBUG msg="push/pull successful" interval=5s419alpha # [348155.778107] alpha data-mesher[213]: time=2026-10-20T10:06:00.749Z level=DEBUG msg="attempting push/pull" peer_count=2420alpha # [348155.778615] alpha data-mesher[213]: time=2026-10-20T10:06:00.750Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s421alpha # [348155.779322] alpha data-mesher[213]: time=2026-10-20T10:06:00.751Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK422alpha # [348155.779322] alpha data-mesher[213]: time=2026-10-20T10:06:00.751Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s423alpha # [348155.779405] alpha data-mesher[213]: time=2026-10-20T10:06:00.751Z level=DEBUG msg="push/pull successful" interval=5s424alpha # [348155.779807] alpha data-mesher[213]: time=2026-10-20T10:06:00.751Z level=INFO msg="received file request" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_file425alpha # [348155.782729] alpha data-mesher[213]: time=2026-10-20T10:06:00.754Z level=INFO msg="file transfer complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_file426beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.05 seconds)427gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file428gamma: (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)429beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key430beta # [348155.899283] beta data-mesher[214]: time=2026-10-20T10:06:00.871Z level=DEBUG msg="attempting push/pull" peer_count=2431beta # [348155.900094] beta data-mesher[214]: time=2026-10-20T10:06:00.871Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s432beta # [348155.900398] beta data-mesher[214]: time=2026-10-20T10:06:00.872Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK433beta # [348155.900480] beta data-mesher[214]: time=2026-10-20T10:06:00.872Z level=DEBUG msg="new file detected" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK name=test_file name=test_file434beta # [348155.900480] beta data-mesher[214]: time=2026-10-20T10:06:00.872Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s435beta # [348155.900602] beta data-mesher[214]: time=2026-10-20T10:06:00.872Z level=DEBUG msg="push/pull successful" interval=5s436beta # [348155.900602] beta data-mesher[214]: time=2026-10-20T10:06:00.872Z level=INFO msg="scheduling file download" name=test_file437beta # [348155.900602] beta data-mesher[214]: time=2026-10-20T10:06:00.872Z level=INFO msg="downloading file" name=test_file signed_at="2026-10-20 10:05:56.049 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK438beta # [348155.909984] beta data-mesher[214]: time=2026-10-20T10:06:00.881Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK439beta # [348155.909984] beta data-mesher[214]: time=2026-10-20T10:06:00.881Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK440beta # [348155.910111] beta data-mesher[214]: time=2026-10-20T10:06:00.881Z level=DEBUG msg="new file detected" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK name=test_file name=test_file441beta # [348155.917444] beta data-mesher[214]: time=2026-10-20T10:06:00.889Z level=INFO msg="download complete" name=test_file signed_at="2026-10-20 10:05:56.049 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK written=true elapsed=16.882391ms442beta # [348156.242255] beta data-mesher[214]: time=2026-10-20T10:06:01.213Z level=INFO msg=http_request uri=/files/test_file status=204443beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.09 seconds)444alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file445beta # [348160.901516] beta data-mesher[214]: time=2026-10-20T10:06:05.873Z level=DEBUG msg="attempting push/pull" peer_count=2446alpha # [348160.780202] alpha data-mesher[213]: time=2026-10-20T10:06:05.752Z level=DEBUG msg="attempting push/pull" peer_count=2447gamma # [348160.781082] gamma data-mesher[213]: time=2026-10-20T10:06:05.752Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg448alpha # [348160.780202] alpha data-mesher[213]: time=2026-10-20T10:06:05.752Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s449gamma # [348160.781082] gamma data-mesher[213]: time=2026-10-20T10:06:05.752Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg450alpha # [348160.781359] alpha data-mesher[213]: time=2026-10-20T10:06:05.753Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK451gamma # [348160.902262] gamma data-mesher[213]: time=2026-10-20T10:06:05.874Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV452beta # [348160.901516] beta data-mesher[214]: time=2026-10-20T10:06:05.873Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s453gamma # [348160.902262] gamma data-mesher[213]: time=2026-10-20T10:06:05.874Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV454beta # [348160.911285] beta data-mesher[214]: time=2026-10-20T10:06:05.883Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK455gamma # [348160.910709] gamma data-mesher[213]: time=2026-10-20T10:06:05.882Z level=DEBUG msg="attempting push/pull" peer_count=2456beta # [348160.911285] beta data-mesher[214]: time=2026-10-20T10:06:05.883Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK457gamma # [348161.453234] gamma data-mesher[213]: time=2026-10-20T10:06:05.882Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s458alpha # [348160.781439] alpha data-mesher[213]: time=2026-10-20T10:06:05.753Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s459gamma # [348161.453234] gamma data-mesher[213]: time=2026-10-20T10:06:05.883Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV460alpha # [348160.781439] alpha data-mesher[213]: time=2026-10-20T10:06:05.753Z level=DEBUG msg="push/pull successful" interval=5s461gamma # [348161.456359] gamma data-mesher[213]: time=2026-10-20T10:06:06.428Z level=DEBUG msg="imported tombstone" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV name=test_file written=true462gamma # [348161.456634] gamma data-mesher[213]: time=2026-10-20T10:06:06.428Z level=DEBUG msg="imported tombstone" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV name=test_file written=false463gamma # [348161.456634] gamma data-mesher[213]: time=2026-10-20T10:06:06.428Z level=INFO msg="state exchange complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s464gamma # [348161.456759] gamma data-mesher[213]: time=2026-10-20T10:06:06.428Z level=DEBUG msg="push/pull successful" interval=5s465beta # [348161.456703] beta data-mesher[214]: time=2026-10-20T10:06:06.428Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK466beta # [348161.456703] beta data-mesher[214]: time=2026-10-20T10:06:06.428Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s467beta # [348161.456894] beta data-mesher[214]: time=2026-10-20T10:06:06.428Z level=DEBUG msg="push/pull successful" interval=5s468gamma # [348165.782491] gamma data-mesher[213]: time=2026-10-20T10:06:10.754Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg469gamma # [348165.782491] gamma data-mesher[213]: time=2026-10-20T10:06:10.754Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg470alpha # [348165.781769] alpha data-mesher[213]: time=2026-10-20T10:06:10.753Z level=DEBUG msg="attempting push/pull" peer_count=2471alpha # [348165.781769] alpha data-mesher[213]: time=2026-10-20T10:06:10.753Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s472alpha # [348165.810431] alpha data-mesher[213]: time=2026-10-20T10:06:10.754Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK473alpha # [348165.818262] alpha data-mesher[213]: time=2026-10-20T10:06:10.790Z level=DEBUG msg="imported tombstone" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK name=test_file written=true474alpha # [348165.818262] alpha data-mesher[213]: time=2026-10-20T10:06:10.790Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s475alpha # [348165.818410] alpha data-mesher[213]: time=2026-10-20T10:06:10.790Z level=DEBUG msg="push/pull successful" interval=5s476alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.08 seconds)477beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file478beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)479gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file480gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)481alpha: must succeed: cat /nix/store/93v1ic7sc4hnwml3gwh4mc1y1d17rp3q-per-machine-alpha-data-mesher-node-identity_identity.pub482alpha: (finished: must succeed: cat /nix/store/93v1ic7sc4hnwml3gwh4mc1y1d17rp3q-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)483alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file484alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)485alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert486beta # [348166.457078] beta data-mesher[214]: time=2026-10-20T10:06:11.428Z level=DEBUG msg="attempting push/pull" peer_count=2487beta # [348167.379432] beta data-mesher[214]: time=2026-10-20T10:06:11.428Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s488beta # [348167.379432] beta data-mesher[214]: time=2026-10-20T10:06:11.430Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK489gamma # [348166.457650] gamma data-mesher[213]: time=2026-10-20T10:06:11.429Z level=DEBUG msg="attempting push/pull" peer_count=2490gamma # [348166.457650] gamma data-mesher[213]: time=2026-10-20T10:06:11.429Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s491gamma # [348167.380806] gamma data-mesher[213]: time=2026-10-20T10:06:11.429Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV492gamma # [348167.380806] gamma data-mesher[213]: time=2026-10-20T10:06:11.429Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV493gamma # [348167.380806] gamma data-mesher[213]: time=2026-10-20T10:06:11.430Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV494gamma # [348167.380806] gamma data-mesher[213]: time=2026-10-20T10:06:11.430Z level=INFO msg="state exchange complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s495gamma # [348167.380806] gamma data-mesher[213]: time=2026-10-20T10:06:11.430Z level=DEBUG msg="push/pull successful" interval=5s496alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 1.03 seconds)497alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8498alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8, in 0.01 seconds)499beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8500beta # [348167.379432] beta data-mesher[214]: time=2026-10-20T10:06:11.430Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s501beta # [348167.379432] beta data-mesher[214]: time=2026-10-20T10:06:11.430Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK502beta # [348167.379432] beta data-mesher[214]: time=2026-10-20T10:06:11.430Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK503beta # [348167.379432] beta data-mesher[214]: time=2026-10-20T10:06:11.430Z level=DEBUG msg="push/pull successful" interval=5s504alpha # [348167.380799] alpha data-mesher[213]: time=2026-10-20T10:06:12.352Z level=INFO msg=http_request uri=/files/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 status=204505alpha # [348170.819119] alpha data-mesher[213]: time=2026-10-20T10:06:15.790Z level=DEBUG msg="attempting push/pull" peer_count=2506alpha # [348170.819119] alpha data-mesher[213]: time=2026-10-20T10:06:15.791Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s507alpha # [348170.820927] alpha data-mesher[213]: time=2026-10-20T10:06:15.792Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV508alpha # [348170.821008] alpha data-mesher[213]: time=2026-10-20T10:06:15.792Z level=INFO msg="state exchange complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s509alpha # [348170.821008] alpha data-mesher[213]: time=2026-10-20T10:06:15.792Z level=DEBUG msg="push/pull successful" interval=5s510alpha # [348170.821464] alpha data-mesher[213]: time=2026-10-20T10:06:15.793Z level=INFO msg="received file request" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8511alpha # [348170.825909] alpha data-mesher[213]: time=2026-10-20T10:06:15.797Z level=INFO msg="file transfer complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8512beta # [348170.819987] beta data-mesher[214]: time=2026-10-20T10:06:15.791Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg513beta # [348170.820807] beta data-mesher[214]: time=2026-10-20T10:06:15.792Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg514beta # [348170.820807] beta data-mesher[214]: time=2026-10-20T10:06:15.792Z level=DEBUG msg="new file detected" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg name=test_file name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8515beta # [348170.820807] beta data-mesher[214]: time=2026-10-20T10:06:15.792Z level=INFO msg="scheduling file download" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8516beta # [348170.820909] beta data-mesher[214]: time=2026-10-20T10:06:15.792Z level=INFO msg="downloading file" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 signed_at="2026-10-20 10:06:11.355 +0000 UTC" signed_by="54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B/9RtV8=" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg517beta # [348170.827802] beta data-mesher[214]: time=2026-10-20T10:06:15.799Z level=INFO msg="download complete" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 signed_at="2026-10-20 10:06:11.355 +0000 UTC" signed_by="54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B/9RtV8=" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg written=true elapsed=6.681852ms518beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8, in 4.04 seconds)519gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8520gamma # [348171.459650] gamma data-mesher[213]: time=2026-10-20T10:06:16.431Z level=DEBUG msg="attempting push/pull" peer_count=2521gamma # [348171.459650] gamma data-mesher[213]: time=2026-10-20T10:06:16.431Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s522gamma # [348171.817419] gamma data-mesher[213]: time=2026-10-20T10:06:16.432Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV523gamma # [348171.817419] gamma data-mesher[213]: time=2026-10-20T10:06:16.432Z level=DEBUG msg="new file detected" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV name=test_file name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8524beta # [348171.458840] beta data-mesher[214]: time=2026-10-20T10:06:16.430Z level=DEBUG msg="attempting push/pull" peer_count=2525gamma # [348171.817419] gamma data-mesher[213]: time=2026-10-20T10:06:16.432Z level=INFO msg="state exchange complete" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV timeout=5s526beta # [348171.458840] beta data-mesher[214]: time=2026-10-20T10:06:16.430Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s527gamma # [348171.817419] gamma data-mesher[213]: time=2026-10-20T10:06:16.432Z level=DEBUG msg="push/pull successful" interval=5s528beta # [348171.817601] beta data-mesher[214]: time=2026-10-20T10:06:16.432Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK529gamma # [348171.817419] gamma data-mesher[213]: time=2026-10-20T10:06:16.432Z level=INFO msg="scheduling file download" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8530beta # [348171.817601] beta data-mesher[214]: time=2026-10-20T10:06:16.432Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK531gamma # [348171.817419] gamma data-mesher[213]: time=2026-10-20T10:06:16.432Z level=INFO msg="downloading file" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 signed_at="2026-10-20 10:06:11.355 +0000 UTC" signed_by="54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B/9RtV8=" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV532beta # [348171.817601] beta data-mesher[214]: time=2026-10-20T10:06:16.432Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg533beta # [348171.817601] beta data-mesher[214]: time=2026-10-20T10:06:16.433Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s534beta # [348171.817601] beta data-mesher[214]: time=2026-10-20T10:06:16.433Z level=INFO msg="received file request" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8535alpha # [348171.459696] alpha data-mesher[213]: time=2026-10-20T10:06:16.431Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV536alpha # [348171.459696] alpha data-mesher[213]: time=2026-10-20T10:06:16.431Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV537gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8, in 1.02 seconds)538alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert539alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)540alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub541beta # [348171.817601] beta data-mesher[214]: time=2026-10-20T10:06:16.433Z level=DEBUG msg="push/pull successful" interval=5s542beta # [348171.817601] beta data-mesher[214]: time=2026-10-20T10:06:16.436Z level=INFO msg="file transfer complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK network="qIFePQ90mrGeQLGua4OH7yU5aLHa2Qp+G7v0Ph0fdR8=" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8543gamma # [348171.819572] gamma data-mesher[213]: time=2026-10-20T10:06:16.791Z level=INFO msg="download complete" name=test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 signed_at="2026-10-20 10:06:11.355 +0000 UTC" signed_by="54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B/9RtV8=" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV written=true elapsed=358.47738ms544Error: failed to update file: 403 Forbidden, signer 54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B/9RtV8= is not authorized for this file test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8545alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/9qxnsi9c5b5bfd3kl68w4dkyvzmx8s93-shared-data-mesher-network_network.pub, in 0.03 seconds)546(finished: run the VM test script, in 34.67 seconds)547alpha # [348172.492653] alpha data-mesher[213]: time=2026-10-20T10:06:17.464Z level=INFO msg=http_request uri=/files/test_ns/54iWDnowgjr9vG9mnrtqzMyCvYneUd4jXfM2B_9RtV8 status=403548gamma # [348175.822263] gamma data-mesher[213]: time=2026-10-20T10:06:20.793Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg549gamma # [348175.822263] gamma data-mesher[213]: time=2026-10-20T10:06:20.794Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg550alpha # [348175.821452] alpha data-mesher[213]: time=2026-10-20T10:06:20.793Z level=DEBUG msg="attempting push/pull" peer_count=2551alpha # [348175.821452] alpha data-mesher[213]: time=2026-10-20T10:06:20.793Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s552alpha # [348175.823136] alpha data-mesher[213]: time=2026-10-20T10:06:20.794Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK553alpha # [348175.823748] alpha data-mesher[213]: time=2026-10-20T10:06:20.795Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s554alpha # [348175.823822] alpha data-mesher[213]: time=2026-10-20T10:06:20.795Z level=DEBUG msg="push/pull successful" interval=5s555beta # [348176.461764] beta data-mesher[214]: time=2026-10-20T10:06:21.433Z level=DEBUG msg="attempting push/pull" peer_count=2556beta # [348176.462455] beta data-mesher[214]: time=2026-10-20T10:06:21.433Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s557beta # [348176.463537] beta data-mesher[214]: time=2026-10-20T10:06:21.435Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK558beta # [348176.464186] beta data-mesher[214]: time=2026-10-20T10:06:21.436Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s559beta # [348176.464273] beta data-mesher[214]: time=2026-10-20T10:06:21.436Z level=DEBUG msg="push/pull successful" interval=5s560gamma # [348176.461868] gamma data-mesher[213]: time=2026-10-20T10:06:21.433Z level=DEBUG msg="attempting push/pull" peer_count=2561gamma # [348176.462025] gamma data-mesher[213]: time=2026-10-20T10:06:21.433Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s562alpha # [348176.462609] alpha data-mesher[213]: time=2026-10-20T10:06:21.434Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK563gamma # [348176.463370] gamma data-mesher[213]: time=2026-10-20T10:06:21.434Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV564gamma # [348176.463370] gamma data-mesher[213]: time=2026-10-20T10:06:21.434Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV565gamma # [348176.463581] gamma data-mesher[213]: time=2026-10-20T10:06:21.435Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg566gamma # [348176.464225] gamma data-mesher[213]: time=2026-10-20T10:06:21.436Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s567alpha # [348176.462609] alpha data-mesher[213]: time=2026-10-20T10:06:21.434Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK568gamma # [348176.464378] gamma data-mesher[213]: time=2026-10-20T10:06:21.436Z level=DEBUG msg="push/pull successful" interval=5s569alpha # [348180.824637] alpha data-mesher[213]: time=2026-10-20T10:06:25.796Z level=DEBUG msg="attempting push/pull" peer_count=2570gamma # [348180.825417] gamma data-mesher[213]: time=2026-10-20T10:06:25.797Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg571alpha # [348181.368880] alpha data-mesher[213]: time=2026-10-20T10:06:25.796Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s572gamma # [348180.825417] gamma data-mesher[213]: time=2026-10-20T10:06:25.797Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg573alpha # [348181.368880] alpha data-mesher[213]: time=2026-10-20T10:06:25.798Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK574alpha # [348181.368880] alpha data-mesher[213]: time=2026-10-20T10:06:25.798Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s575alpha # [348181.368880] alpha data-mesher[213]: time=2026-10-20T10:06:25.799Z level=DEBUG msg="push/pull successful" interval=5s576alpha # [348181.465162] alpha data-mesher[213]: time=2026-10-20T10:06:26.436Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV577alpha # [348181.465162] alpha data-mesher[213]: time=2026-10-20T10:06:26.437Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV578alpha # [348181.642807] alpha data-mesher[213]: time=2026-10-20T10:06:26.437Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK579alpha # [348181.642807] alpha data-mesher[213]: time=2026-10-20T10:06:26.437Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK580gamma # [348181.464573] gamma data-mesher[213]: time=2026-10-20T10:06:26.436Z level=DEBUG msg="attempting push/pull" peer_count=2581gamma # [348181.642703] gamma data-mesher[213]: time=2026-10-20T10:06:26.436Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s582gamma # [348181.642703] gamma data-mesher[213]: time=2026-10-20T10:06:26.438Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg583gamma # [348181.642703] gamma data-mesher[213]: time=2026-10-20T10:06:26.438Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s584gamma # [348181.642703] gamma data-mesher[213]: time=2026-10-20T10:06:26.438Z level=DEBUG msg="push/pull successful" interval=5s585beta # [348181.464761] beta data-mesher[214]: time=2026-10-20T10:06:26.436Z level=DEBUG msg="attempting push/pull" peer_count=2586beta # [348181.464761] beta data-mesher[214]: time=2026-10-20T10:06:26.436Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s587beta # [348181.642938] beta data-mesher[214]: time=2026-10-20T10:06:26.437Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg588beta # [348181.642938] beta data-mesher[214]: time=2026-10-20T10:06:26.438Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s589beta # [348181.642938] beta data-mesher[214]: time=2026-10-20T10:06:26.438Z level=DEBUG msg="push/pull successful" interval=5s590alpha # [348185.827507] alpha data-mesher[213]: time=2026-10-20T10:06:30.799Z level=DEBUG msg="attempting push/pull" peer_count=2591alpha # [348185.827891] alpha data-mesher[213]: time=2026-10-20T10:06:30.799Z level=DEBUG msg="initiating state exchange" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s592alpha # [348185.828678] alpha data-mesher[213]: time=2026-10-20T10:06:30.800Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK593alpha # [348185.829272] alpha data-mesher[213]: time=2026-10-20T10:06:30.801Z level=INFO msg="state exchange complete" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK timeout=5s594alpha # [348185.829334] alpha data-mesher[213]: time=2026-10-20T10:06:30.801Z level=DEBUG msg="push/pull successful" interval=5s595gamma # [348185.828501] gamma data-mesher[213]: time=2026-10-20T10:06:30.799Z level=INFO msg="received state sync from peer" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg596gamma # [348185.828501] gamma data-mesher[213]: time=2026-10-20T10:06:30.799Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg597gamma # [348186.468133] gamma data-mesher[213]: time=2026-10-20T10:06:31.439Z level=DEBUG msg="attempting push/pull" peer_count=2598gamma # [348186.468133] gamma data-mesher[213]: time=2026-10-20T10:06:31.440Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s599gamma # [348186.469529] gamma data-mesher[213]: time=2026-10-20T10:06:31.441Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg600gamma # [348186.469859] gamma data-mesher[213]: time=2026-10-20T10:06:31.441Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s601gamma # [348186.469934] gamma data-mesher[213]: time=2026-10-20T10:06:31.441Z level=DEBUG msg="push/pull successful" interval=5s602alpha # [348186.468090] alpha data-mesher[213]: time=2026-10-20T10:06:31.439Z level=INFO msg="received state sync from peer" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV603alpha # [348186.468090] alpha data-mesher[213]: time=2026-10-20T10:06:31.439Z level=INFO msg="merging remote state" peer=12D3KooWNSx8ASztZJyUPTqLsZQHTBE1AFzTkE1T9PT1H3zVqSUV604alpha # [348186.468661] alpha data-mesher[213]: time=2026-10-20T10:06:31.440Z level=INFO msg="received state sync from peer" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK605alpha # [348186.468661] alpha data-mesher[213]: time=2026-10-20T10:06:31.440Z level=INFO msg="merging remote state" peer=12D3KooWALBBMCAJaJkzVUb18oojCQfegNbCfTf4eFYQ19mGyeHK606beta # [348186.467410] beta data-mesher[214]: time=2026-10-20T10:06:31.439Z level=DEBUG msg="attempting push/pull" peer_count=2607beta # [348186.467410] beta data-mesher[214]: time=2026-10-20T10:06:31.439Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s608beta # [348186.469104] beta data-mesher[214]: time=2026-10-20T10:06:31.440Z level=INFO msg="merging remote state" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg609beta # [348186.469693] beta data-mesher[214]: time=2026-10-20T10:06:31.441Z level=INFO msg="state exchange complete" peer=12D3KooWRQB9LJEoFJFtW9NY3vkEtaqCd1ZSEGzWLzNsprdsKBHg timeout=5s610beta # [348186.469779] beta data-mesher[214]: time=2026-10-20T10:06:31.441Z level=DEBUG msg="push/pull successful" interval=5s611test script finished in 49.07s612cleanup613kill NspawnMachine (pid 52)614kill NspawnMachine (pid 54)615kill NspawnMachine (pid 55)616Container alpha terminated by signal KILL.617Container beta terminated by signal KILL.618(finished: cleanup, in 0.44 seconds)619Container gamma terminated by signal KILL.