container-test-run-data-mesher
checks.aarch64-linux.data-mesher
· build #964
· raw
1build01: building sl2arl3rfnqdrd78kf8sbbkwl43jg4k5-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)56Test 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 56)16alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...17gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...18beta: Waiting for journal at /build/vm-state-beta/var/log/journal...19(finished: start all VMs, in 0.00 seconds)20alpha: waiting for unit data-mesher.service21nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE22nixos-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.23nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE24nixos-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.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 beta on /build/vm-state-beta.31░ Spawning container alpha on /build/vm-state-alpha.32░ Spawning container gamma on /build/vm-state-gamma.33alpha # No journal files were found.34alpha # No journal boot entry found for the specified boot (+0).35gamma # No journal files were found.36gamma # No journal boot entry found for the specified boot (+0).37beta # No journal files were found.38beta # No journal boot entry found for the specified boot (+0).39alpha # [361996.886362] alpha systemd-journald[90]: Journal started40alpha # [361996.886424] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/dd77ee047d7548f8998e0accf0fb28dd) is 8M, max 2.5G, 2.4G free.41alpha # [361996.895831] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.42alpha # [361996.908397] alpha systemd[1]: Starting Flush Journal to Persistent Storage...43alpha # [361996.909139] alpha systemd[1]: Starting Network Name Resolution...44alpha # [361996.909896] alpha systemd[1]: Starting Create Static Device Nodes in /dev...45alpha # [361996.917196] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/dd77ee047d7548f8998e0accf0fb28dd is 2.323ms for 6 entries.46alpha # [361996.917196] alpha systemd-journald[90]: System Journal (/var/log/journal/dd77ee047d7548f8998e0accf0fb28dd) is 8M, max 4G, 3.9G free.47alpha # [361996.945059] alpha systemd[1]: Finished Create Static Device Nodes in /dev.48alpha # [361996.945673] alpha systemd[1]: Reached target Preparation for Local File Systems.49alpha # [361996.945783] alpha systemd[1]: Reached target Local File Systems.50alpha # [361996.946606] alpha systemd[1]: Listening on Boot Loader Control Service Socket.51alpha # [361996.946657] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container52alpha # [361996.947533] alpha systemd[1]: Starting Save Transient machine-id to Disk...53alpha # [361996.947568] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys54alpha # [361996.981425] alpha systemd[1]: Finished Flush Journal to Persistent Storage.55alpha # [361996.982950] alpha systemd[1]: Starting Create System Files and Directories...56alpha # [361997.002546] alpha systemd-tmpfiles[162]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted57alpha # [361997.002725] alpha systemd-tmpfiles[162]: fchmod() of /var/log/journal failed: Operation not permitted58alpha # [361997.002848] alpha systemd-tmpfiles[162]: fchmod() of /var/log/journal/dd77ee047d7548f8998e0accf0fb28dd failed: Operation not permitted59alpha # [361997.003036] alpha systemd-tmpfiles[162]: fchmod() of /run/log/journal failed: Operation not permitted60alpha # [361997.004341] alpha systemd[1]: Finished Create System Files and Directories.61alpha # [361997.005458] alpha systemd[1]: Starting Rebuild Journal Catalog...62alpha # [361997.006228] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...63alpha # [361997.021061] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.64alpha # [361997.025442] alpha systemd[1]: Finished Rebuild Journal Catalog.65alpha # [361997.026800] alpha systemd[1]: Starting Update is Completed...66alpha # [361997.036427] alpha systemd[1]: Finished Update is Completed.67alpha # [361997.051808] alpha systemd[1]: Finished Firewall.68alpha # [361997.051998] alpha systemd[1]: Reached target Preparation for Network.69alpha # [361997.052250] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.70alpha # [361997.053327] alpha systemd[1]: Starting Network Management...71alpha # [361997.703120] alpha systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted72alpha # [361997.703208] alpha systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted73alpha # [361997.712415] 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.74alpha # [361997.712579] 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.75alpha # [361997.712730] alpha systemd-networkd[207]: lo: Link UP76alpha # [361997.712735] alpha systemd-networkd[207]: lo: Gained carrier77alpha # [361997.712905] alpha systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.78alpha # [361997.713367] alpha systemd-networkd[207]: eth1: Link UP79alpha # [361997.713679] alpha systemd-networkd[207]: eth1: Gained carrier80alpha # [361997.714034] alpha systemd[1]: Started Network Management.81alpha # [361997.715157] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...82alpha # [361997.762724] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.83gamma # [361996.881114] gamma systemd-journald[90]: Journal started84gamma # [361996.881175] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/0b2d769cadfd4c8ab85e1c0eb6cf95b4) is 8M, max 2.5G, 2.4G free.85gamma # [361996.883005] gamma systemd[1]: Finished Apply Kernel Variables.86gamma # [361996.894120] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.87gamma # [361996.906672] gamma systemd[1]: Starting Flush Journal to Persistent Storage...88gamma # [361996.907701] gamma systemd[1]: Starting Network Name Resolution...89gamma # [361996.908343] gamma systemd[1]: Starting Create Static Device Nodes in /dev...90gamma # [361996.916472] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/0b2d769cadfd4c8ab85e1c0eb6cf95b4 is 1.621ms for 7 entries.91gamma # [361996.916472] gamma systemd-journald[90]: System Journal (/var/log/journal/0b2d769cadfd4c8ab85e1c0eb6cf95b4) is 8M, max 4G, 3.9G free.92gamma # [361996.942951] gamma systemd[1]: Finished Create Static Device Nodes in /dev.93gamma # [361996.943506] gamma systemd[1]: Reached target Preparation for Local File Systems.94gamma # [361996.943616] gamma systemd[1]: Reached target Local File Systems.95gamma # [361996.944459] gamma systemd[1]: Listening on Boot Loader Control Service Socket.96gamma # [361996.944506] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container97gamma # [361996.945303] gamma systemd[1]: Starting Save Transient machine-id to Disk...98gamma # [361996.945336] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys99gamma # [361996.981952] gamma systemd[1]: Finished Flush Journal to Persistent Storage.100gamma # [361996.982923] gamma systemd[1]: Starting Create System Files and Directories...101gamma # [361997.002219] gamma systemd-tmpfiles[163]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted102gamma # [361997.002401] gamma systemd-tmpfiles[163]: fchmod() of /var/log/journal failed: Operation not permitted103gamma # [361997.002527] gamma systemd-tmpfiles[163]: fchmod() of /var/log/journal/0b2d769cadfd4c8ab85e1c0eb6cf95b4 failed: Operation not permitted104gamma # [361997.002717] gamma systemd-tmpfiles[163]: fchmod() of /run/log/journal failed: Operation not permitted105gamma # [361997.003888] gamma systemd[1]: Finished Create System Files and Directories.106gamma # [361997.005727] gamma systemd[1]: Starting Rebuild Journal Catalog...107gamma # [361997.006498] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...108gamma # [361997.018916] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.109gamma # [361997.030579] gamma systemd[1]: Finished Rebuild Journal Catalog.110gamma # [361997.031646] gamma systemd[1]: Starting Update is Completed...111gamma # [361997.041731] gamma systemd[1]: Finished Update is Completed.112gamma # [361997.049828] gamma systemd[1]: Finished Firewall.113gamma # [361997.050055] gamma systemd[1]: Reached target Preparation for Network.114gamma # [361997.050408] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.115gamma # [361997.052059] gamma systemd[1]: Starting Network Management...116gamma # [361997.704992] gamma systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted117gamma # [361997.705086] gamma systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted118gamma # [361997.714312] 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.119gamma # [361997.714481] 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.120gamma # [361997.714628] gamma systemd-networkd[207]: lo: Link UP121gamma # [361997.714633] gamma systemd-networkd[207]: lo: Gained carrier122gamma # [361997.714813] gamma systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.123gamma # [361997.715243] gamma systemd-networkd[207]: eth1: Link UP124gamma # [361997.715543] gamma systemd-networkd[207]: eth1: Gained carrier125beta # [361996.877872] beta systemd-journald[90]: Journal started126gamma # [361997.715884] gamma systemd[1]: Started Network Management.127beta # [361996.877933] beta systemd-journald[90]: Runtime Journal (/run/log/journal/93fa8507a49340d9a8df0a858baff744) is 8M, max 2.5G, 2.4G free.128gamma # [361997.717126] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...129beta # [361996.880634] beta systemd[1]: Finished Apply Kernel Variables.130gamma # [361997.760903] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.131beta # [361996.891977] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.132beta # [361996.904611] beta systemd[1]: Starting Flush Journal to Persistent Storage...133beta # [361996.905570] beta systemd[1]: Starting Network Name Resolution...134beta # [361996.906333] beta systemd[1]: Starting Create Static Device Nodes in /dev...135beta # [361996.915513] beta systemd-journald[90]: Time spent on flushing to /var/log/journal/93fa8507a49340d9a8df0a858baff744 is 2.302ms for 7 entries.136beta # [361996.915513] beta systemd-journald[90]: System Journal (/var/log/journal/93fa8507a49340d9a8df0a858baff744) is 8M, max 4G, 3.9G free.137beta # [361996.941701] beta systemd[1]: Finished Create Static Device Nodes in /dev.138beta # [361996.942272] beta systemd[1]: Reached target Preparation for Local File Systems.139beta # [361996.942384] beta systemd[1]: Reached target Local File Systems.140beta # [361996.943234] beta systemd[1]: Listening on Boot Loader Control Service Socket.141beta # [361996.943280] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container142beta # [361996.944206] beta systemd[1]: Starting Save Transient machine-id to Disk...143beta # [361996.944244] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys144beta # [361996.982129] beta systemd[1]: Finished Flush Journal to Persistent Storage.145beta # [361996.983020] beta systemd[1]: Starting Create System Files and Directories...146beta # [361997.001357] beta systemd-tmpfiles[163]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted147beta # [361997.001581] beta systemd-tmpfiles[163]: fchmod() of /var/log/journal failed: Operation not permitted148beta # [361997.001746] beta systemd-tmpfiles[163]: fchmod() of /var/log/journal/93fa8507a49340d9a8df0a858baff744 failed: Operation not permitted149beta # [361997.001983] beta systemd-tmpfiles[163]: fchmod() of /run/log/journal failed: Operation not permitted150beta # [361997.003867] beta systemd[1]: Finished Create System Files and Directories.151beta # [361997.005227] beta systemd[1]: Starting Rebuild Journal Catalog...152beta # [361997.006172] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...153beta # [361997.019633] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.154beta # [361997.024368] beta systemd[1]: Finished Rebuild Journal Catalog.155beta # [361997.025591] beta systemd[1]: Starting Update is Completed...156beta # [361997.036722] beta systemd[1]: Finished Update is Completed.157beta # [361997.050769] beta systemd[1]: Finished Firewall.158beta # [361997.050933] beta systemd[1]: Reached target Preparation for Network.159beta # [361997.051177] beta systemd[1]: Listening on Network Management Resolve Hook Socket.160beta # [361997.052357] beta systemd[1]: Starting Network Management...161beta # [361997.709124] beta systemd-networkd[207]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted162beta # [361997.709211] beta systemd-networkd[207]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted163beta # [361997.718452] 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.164beta # [361997.718614] 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.165beta # [361997.718752] beta systemd-networkd[207]: lo: Link UP166beta # [361997.718756] beta systemd-networkd[207]: lo: Gained carrier167beta # [361997.718938] beta systemd-networkd[207]: eth1: Configuring with /etc/systemd/network/40-eth1.network.168beta # [361997.719273] beta systemd[1]: Started Network Management.169beta # [361997.719363] beta systemd-networkd[207]: eth1: Link UP170beta # [361997.719626] beta systemd-networkd[207]: eth1: Gained carrier171beta # [361997.752625] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...172beta # [361997.765790] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.173beta # [361998.061731] beta systemd-resolved[121]: Positive Trust Anchors:174beta # [361998.061742] beta systemd-resolved[121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d175beta # [361998.061746] beta systemd-resolved[121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16176beta # [361998.061779] beta systemd-resolved[121]: 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 test177beta # [361998.081871] beta systemd-resolved[121]: Using system hostname 'beta'.178beta # [361998.083222] beta systemd[1]: Started Network Name Resolution.179beta # [361998.083383] beta systemd[1]: Reached target Network.180beta # [361998.083518] beta systemd[1]: Reached target System Initialization.181beta # [361998.083618] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container182beta # [361998.083680] beta systemd[1]: Started Daily Cleanup of Temporary Directories.183beta # [361998.083716] beta systemd[1]: Reached target Timer Units.184beta # [361998.083949] beta systemd[1]: Listening on D-Bus System Message Bus Socket.185beta # [361998.084199] beta systemd[1]: Listening on Nix Daemon Socket.186beta # [361998.084423] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.187beta # [361998.084467] beta systemd[1]: Reached target Socket Units.188beta # [361998.084547] beta systemd[1]: Reached target Basic System.189beta # [361998.096789] beta systemd[1]: Starting data mesher daemon...190beta # [361998.097618] beta systemd[1]: Starting Import lastlog data into lastlog2 database...191beta # [361998.098444] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...192beta # [361998.099813] beta systemd[1]: Starting D-Bus System Message Bus...193beta # [361998.121246] beta systemd[1]: Finished Import lastlog data into lastlog2 database.194beta # [361998.240499] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.195beta # [361998.242002] beta systemd[1]: Finished Save Transient machine-id to Disk.196beta # [361998.341558] beta nsncd[214]: Oct 13 15:38:21.298 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"197beta # [361998.341643] beta systemd[1]: Started Name Service Cache Daemon (nsncd).198beta # [361998.341755] beta systemd[1]: Reached target Host and Network Name Lookups.199beta # [361998.341868] beta systemd[1]: Reached target User and Group Name Lookups.200alpha # [361998.033710] alpha systemd-resolved[120]: Positive Trust Anchors:201alpha # [361998.033721] alpha systemd-resolved[120]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d202alpha # [361998.033725] alpha systemd-resolved[120]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16203alpha # [361998.033760] alpha systemd-resolved[120]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test204alpha # [361998.054102] alpha systemd-resolved[120]: Using system hostname 'alpha'.205alpha # [361998.055446] alpha systemd[1]: Started Network Name Resolution.206alpha # [361998.055522] alpha systemd[1]: Reached target Network.207alpha # [361998.055580] alpha systemd[1]: Reached target System Initialization.208alpha # [361998.055620] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container209alpha # [361998.055643] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.210alpha # [361998.055658] alpha systemd[1]: Reached target Timer Units.211alpha # [361998.055766] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.212alpha # [361998.055878] alpha systemd[1]: Listening on Nix Daemon Socket.213alpha # [361998.055977] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.214alpha # [361998.055999] alpha systemd[1]: Reached target Socket Units.215alpha # [361998.056060] alpha systemd[1]: Reached target Basic System.216alpha # [361998.057274] alpha systemd[1]: Starting data mesher daemon...217alpha # [361998.057993] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...218alpha # [361998.058792] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...219alpha # [361998.059875] alpha systemd[1]: Starting D-Bus System Message Bus...220alpha # [361998.113207] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.221alpha # [361998.242816] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.222alpha # [361998.244353] alpha systemd[1]: Finished Save Transient machine-id to Disk.223alpha # [361998.348299] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).224alpha # [361998.349297] alpha nsncd[214]: Oct 13 15:38:21.306 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"225alpha # [361998.348427] alpha systemd[1]: Reached target Host and Network Name Lookups.226alpha # [361998.348526] alpha systemd[1]: Reached target User and Group Name Lookups.227gamma # [361998.055583] gamma systemd-resolved[121]: Positive Trust Anchors:228gamma # [361998.055595] gamma systemd-resolved[121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d229gamma # [361998.055599] gamma systemd-resolved[121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16230gamma # [361998.055633] gamma systemd-resolved[121]: 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 test231gamma # [361998.076598] gamma systemd-resolved[121]: Using system hostname 'gamma'.232gamma # [361998.078022] gamma systemd[1]: Started Network Name Resolution.233gamma # [361998.078166] gamma systemd[1]: Reached target Network.234gamma # [361998.078284] gamma systemd[1]: Reached target System Initialization.235gamma # [361998.078377] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container236gamma # [361998.078443] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.237gamma # [361998.078483] gamma systemd[1]: Reached target Timer Units.238gamma # [361998.079074] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.239gamma # [361998.079293] gamma systemd[1]: Listening on Nix Daemon Socket.240gamma # [361998.079593] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.241gamma # [361998.079637] gamma systemd[1]: Reached target Socket Units.242gamma # [361998.079719] gamma systemd[1]: Reached target Basic System.243gamma # [361998.096664] gamma systemd[1]: Starting data mesher daemon...244gamma # [361998.098150] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...245gamma # [361998.100295] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...246gamma # [361998.102277] gamma systemd[1]: Starting D-Bus System Message Bus...247gamma # [361998.121352] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.248gamma # [361998.222104] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.249gamma # [361998.223010] gamma systemd[1]: Finished Save Transient machine-id to Disk.250gamma # [361998.357075] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).251gamma # [361998.357152] gamma systemd[1]: Reached target Host and Network Name Lookups.252gamma # [361998.357207] gamma systemd[1]: Reached target User and Group Name Lookups.253gamma # [361998.357998] gamma nsncd[214]: Oct 13 15:38:21.315 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"254gamma # [361998.384440] gamma systemd[1]: Starting User Login Management...255gamma # [361998.385308] gamma systemd[1]: Starting Permit User Sessions...256gamma # [361998.397976] gamma systemd[1]: Finished Permit User Sessions.257gamma # [361998.399482] gamma systemd[1]: Started Console Getty.258gamma # [361998.399535] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0259gamma # [361998.399560] gamma systemd[1]: Reached target Login Prompts.260gamma # [361998.534146] gamma dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...261gamma # [361998.535094] gamma dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'262gamma # [361998.535094] gamma dbus-broker-launch[215]: Invalid user-name in /nix/store/00ngw45f3i2xmjbmkmf2hwkv1azhl8fb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"263gamma # [361998.535883] gamma systemd[1]: Started D-Bus System Message Bus.264gamma # [361998.544569] gamma dbus-broker-launch[215]: Ready265alpha # [361998.384449] alpha systemd[1]: Starting User Login Management...266alpha # [361998.385674] alpha systemd[1]: Starting Permit User Sessions...267alpha # [361998.397625] alpha systemd[1]: Finished Permit User Sessions.268alpha # [361998.398565] alpha systemd[1]: Started Console Getty.269alpha # [361998.398611] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0270alpha # [361998.398634] alpha systemd[1]: Reached target Login Prompts.271alpha # [361998.534037] alpha dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...272alpha # [361998.534940] alpha dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'273alpha # [361998.534940] alpha dbus-broker-launch[215]: Invalid user-name in /nix/store/00ngw45f3i2xmjbmkmf2hwkv1azhl8fb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"274alpha # [361998.535676] alpha systemd[1]: Started D-Bus System Message Bus.275alpha # [361998.543007] alpha dbus-broker-launch[215]: Ready276beta # [361998.344261] beta systemd[1]: Starting User Login Management...277beta # [361998.345765] beta systemd[1]: Starting Permit User Sessions...278beta # [361998.390847] beta systemd[1]: Finished Permit User Sessions.279beta # [361998.391877] beta systemd[1]: Started Console Getty.280beta # [361998.391921] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0281beta # [361998.391939] beta systemd[1]: Reached target Login Prompts.282beta # [361998.489713] beta dbus-broker-launch[215]: Looking up NSS user entry for 'systemd-timesync'...283beta # [361998.490879] beta dbus-broker-launch[215]: NSS returned no entry for 'systemd-timesync'284beta # [361998.490879] beta dbus-broker-launch[215]: Invalid user-name in /nix/store/00ngw45f3i2xmjbmkmf2hwkv1azhl8fb-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"285beta # [361998.491439] beta systemd[1]: Started D-Bus System Message Bus.286beta # [361998.499699] beta dbus-broker-launch[215]: Ready287beta # [361998.812199] beta systemd-networkd[207]: eth1: Gained IPv6LL288beta # [361998.926718] beta data-mesher[212]: time=2026-10-13T15:38:21.884Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]289beta # [361998.931533] beta data-mesher[212]: time=2026-10-13T15:38:21.885Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2: [/dns/alpha.clan/tcp/7946]} {12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ290beta # [361998.931533] beta data-mesher[212]: time=2026-10-13T15:38:21.885Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml291beta # [361999.005360] beta data-mesher[212]: time=2026-10-13T15:38:21.962Z level=INFO msg="checking file integrity"292beta # [361999.005511] beta data-mesher[212]: time=2026-10-13T15:38:21.963Z level=INFO msg="file integrity check complete"293beta # [361999.009396] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="libp2p host created" peer_id=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ 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]"294beta # [361999.009488] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name295beta # [361999.009488] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name296beta # [361999.009488] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=GET path=/files297beta # [361999.009488] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="starting server"298beta # [361999.009569] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="waiting for DHT to populate" delay=10s299beta # [361999.009716] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="HTTP server listening" address=[::1]:7331300beta # [361999.009716] beta data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331301beta # [361999.018697] beta data-mesher[212]: time=2026-10-13T15:38:21.976Z level=INFO msg="peer connected" peer_id=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 remote_addr=/ip4/192.168.1.1/tcp/7946302beta # [361999.020896] beta data-mesher[212]: time=2026-10-13T15:38:21.978Z level=INFO msg="peer connected" peer_id=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 remote_addr=/ip4/192.168.1.1/tcp/7946303beta # [361999.025535] beta data-mesher[212]: time=2026-10-13T15:38:21.983Z level=INFO msg="peer connected" peer_id=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc remote_addr=/ip4/192.168.1.3/tcp/7946304beta # [361999.029222] beta data-mesher[212]: time=2026-10-13T15:38:21.986Z level=INFO msg="peer connected" peer_id=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc remote_addr=/ip4/192.168.1.3/tcp/7946305gamma # [361998.919734] gamma data-mesher[212]: time=2026-10-13T15:38:21.877Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]306gamma # [361998.920825] gamma data-mesher[212]: time=2026-10-13T15:38:21.878Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2: [/dns/alpha.clan/tcp/7946]} {12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc307gamma # [361998.920825] gamma data-mesher[212]: time=2026-10-13T15:38:21.878Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml308gamma # [361999.005273] gamma data-mesher[212]: time=2026-10-13T15:38:21.962Z level=INFO msg="checking file integrity"309gamma # [361999.005421] gamma data-mesher[212]: time=2026-10-13T15:38:21.963Z level=INFO msg="file integrity check complete"310gamma # [361999.009393] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="libp2p host created" peer_id=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc 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]"311gamma # [361999.009439] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=GET path=/files312gamma # [361999.009439] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name313gamma # [361999.009439] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name314gamma # [361999.009439] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="starting server"315gamma # [361999.009846] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="HTTP server listening" address=[::1]:7331316gamma # [361999.009881] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331317gamma # [361999.010171] gamma data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="waiting for DHT to populate" delay=10s318gamma # [361999.016228] gamma data-mesher[212]: time=2026-10-13T15:38:21.973Z level=INFO msg="peer connected" peer_id=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 remote_addr=/ip4/192.168.1.1/tcp/7946319gamma # [361999.024571] gamma data-mesher[212]: time=2026-10-13T15:38:21.982Z level=INFO msg="peer connected" peer_id=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ remote_addr=/ip4/192.168.1.2/tcp/7946320gamma # [361999.029689] gamma data-mesher[212]: time=2026-10-13T15:38:21.987Z level=INFO msg="peer connected" peer_id=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ remote_addr=/ip4/192.168.1.2/tcp/45196321alpha # [361998.975179] alpha data-mesher[212]: time=2026-10-13T15:38:21.932Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]322alpha # [361998.976314] alpha data-mesher[212]: time=2026-10-13T15:38:21.933Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2: [/dns/alpha.clan/tcp/7946]} {12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2323alpha # [361998.976314] alpha data-mesher[212]: time=2026-10-13T15:38:21.933Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml324alpha # [361999.005414] alpha data-mesher[212]: time=2026-10-13T15:38:21.963Z level=INFO msg="checking file integrity"325alpha # [361999.005556] alpha data-mesher[212]: time=2026-10-13T15:38:21.963Z level=INFO msg="file integrity check complete"326alpha # [361999.009476] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="libp2p host created" peer_id=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 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]"327alpha # [361999.009574] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=GET path=/files328alpha # [361999.009574] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name329alpha # [361999.009574] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name330alpha # [361999.009574] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="starting server"331alpha # [361999.009737] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="waiting for DHT to populate" delay=10s332alpha # [361999.009737] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="HTTP server listening" address=[::1]:7331333alpha # [361999.009815] alpha data-mesher[212]: time=2026-10-13T15:38:21.967Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331334alpha # [361999.016860] alpha data-mesher[212]: time=2026-10-13T15:38:21.974Z level=INFO msg="peer connected" peer_id=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ remote_addr=/ip4/192.168.1.2/tcp/7946335alpha # [361999.017855] alpha data-mesher[212]: time=2026-10-13T15:38:21.975Z level=INFO msg="peer connected" peer_id=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc remote_addr=/ip4/192.168.1.3/tcp/7946336alpha # [361999.020474] alpha data-mesher[212]: time=2026-10-13T15:38:21.978Z level=INFO msg="peer connected" peer_id=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ remote_addr=/ip4/192.168.1.2/tcp/38244337alpha # [361999.180066] alpha systemd-logind[232]: New seat seat0.338alpha # [361999.180300] alpha systemd[1]: Started User Login Management.339alpha # [361999.208693] alpha systemd[1]: Starting linger-users.service...340alpha # [361999.222469] alpha systemd[1]: linger-users.service: Deactivated successfully.341alpha # [361999.222580] alpha systemd[1]: Finished linger-users.service.342gamma # [361999.171059] gamma systemd-logind[232]: New seat seat0.343gamma # [361999.171262] gamma systemd[1]: Started User Login Management.344gamma # [361999.173413] gamma systemd[1]: Starting linger-users.service...345gamma # [361999.219433] gamma systemd[1]: linger-users.service: Deactivated successfully.346gamma # [361999.219636] gamma systemd[1]: Finished linger-users.service.347gamma # [361999.292122] gamma systemd-networkd[207]: eth1: Gained IPv6LL348beta # [361999.171619] beta systemd-logind[232]: New seat seat0.349beta # [361999.171799] beta systemd[1]: Started User Login Management.350beta # [361999.173822] beta systemd[1]: Starting linger-users.service...351beta # [361999.218139] beta systemd[1]: linger-users.service: Deactivated successfully.352beta # [361999.218365] beta systemd[1]: Finished linger-users.service.353alpha # [361999.548127] alpha systemd-networkd[207]: eth1: Gained IPv6LL354alpha: still waiting for container 'alpha' to reach ready state...355gamma # [362009.010440] gamma data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="performing state exchange with peers on join" count=1356gamma # [362009.010440] gamma data-mesher[212]: time=2026-10-13T15:38:31.968Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s357gamma # [362009.011226] gamma data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="received state sync from peer" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ358gamma # [362009.011226] gamma data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ359gamma # [362009.011435] gamma data-mesher[212]: time=2026-10-13T15:38:31.969Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ360gamma # [362009.011435] gamma data-mesher[212]: time=2026-10-13T15:38:31.969Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s361gamma # [362009.011570] gamma data-mesher[212]: time=2026-10-13T15:38:31.969Z level=INFO msg="server started"362gamma # [362009.011628] gamma data-mesher[212]: time=2026-10-13T15:38:31.969Z level=INFO msg="starting expired-file sweeper" interval=1m0s363gamma # [362009.011737] gamma systemd[1]: Started data mesher daemon.364gamma # [362009.012284] gamma systemd[1]: Reached target Multi-User System.365gamma # [362009.012578] gamma systemd[1]: Startup finished in 12.607s.366alpha # [362009.010616] alpha data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="performing state exchange with peers on join" count=1367alpha # [362009.010616] alpha data-mesher[212]: time=2026-10-13T15:38:31.968Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s368alpha # [362009.011289] alpha data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ369alpha # [362009.011289] alpha data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s370alpha # [362009.011404] alpha data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="server started"371alpha # [362009.011443] alpha data-mesher[212]: time=2026-10-13T15:38:31.969Z level=INFO msg="starting expired-file sweeper" interval=1m0s372alpha # [362009.011667] alpha systemd[1]: Started data mesher daemon.373alpha # [362009.012216] alpha systemd[1]: Reached target Multi-User System.374alpha # [362009.012630] alpha systemd[1]: Startup finished in 12.604s.375beta # [362009.010441] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="performing state exchange with peers on join" count=1376beta # [362009.010441] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s377beta # [362009.011241] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="received state sync from peer" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2378beta # [362009.011241] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2379beta # [362009.011241] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc380beta # [362009.011241] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="state exchange complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s381beta # [362009.011241] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="received state sync from peer" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc382beta # [362009.011241] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="server started"383beta # [362009.011241] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc384beta # [362009.011562] beta data-mesher[212]: time=2026-10-13T15:38:31.968Z level=INFO msg="starting expired-file sweeper" interval=1m0s385beta # [362009.011487] beta systemd[1]: Started data mesher daemon.386beta # [362009.012021] beta systemd[1]: Reached target Multi-User System.387beta # [362009.012370] beta systemd[1]: Startup finished in 12.597s.388alpha: (finished: waiting for unit data-mesher.service, in 13.68 seconds)389beta: waiting for unit data-mesher.service390beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)391gamma: waiting for unit data-mesher.service392gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)393alpha: must succeed: echo -n 'hello world' > /tmp/test_file394alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)395alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key396alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)397??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.398 File "/nix/store/x4i03lhka9aiwxswmbhjfzzbl77nlxmj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39399alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file400??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.401 File "/nix/store/x4i03lhka9aiwxswmbhjfzzbl77nlxmj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39402alpha: (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)403beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file404alpha # [362009.606671] alpha data-mesher[212]: time=2026-10-13T15:38:32.564Z level=INFO msg=http_request uri=/files/test_file status=204405gamma # [362014.013134] gamma data-mesher[212]: time=2026-10-13T15:38:36.970Z level=DEBUG msg="attempting push/pull" peer_count=2406gamma # [362014.013816] gamma data-mesher[212]: time=2026-10-13T15:38:36.970Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s407gamma # [362014.013816] gamma data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="received state sync from peer" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2408gamma # [362014.013816] gamma data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2409gamma # [362014.013989] gamma data-mesher[212]: time=2026-10-13T15:38:36.971Z level=DEBUG msg="new file detected" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 name=test_file name=test_file410gamma # [362014.013989] gamma data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="received state sync from peer" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ411gamma # [362014.013989] gamma data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ412gamma # [362014.014137] gamma data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="scheduling file download" name=test_file413gamma # [362014.014137] gamma data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="downloading file" name=test_file signed_at="2026-10-13 15:38:32.56 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2414gamma # [362014.015054] gamma data-mesher[212]: time=2026-10-13T15:38:36.972Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2415gamma # [362014.015138] gamma data-mesher[212]: time=2026-10-13T15:38:36.972Z level=DEBUG msg="new file detected" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 name=test_file name=test_file416gamma # [362014.015138] gamma data-mesher[212]: time=2026-10-13T15:38:36.972Z level=INFO msg="state exchange complete" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s417gamma # [362014.015138] gamma data-mesher[212]: time=2026-10-13T15:38:36.972Z level=DEBUG msg="push/pull successful" interval=5s418gamma # [362014.021793] gamma data-mesher[212]: time=2026-10-13T15:38:36.979Z level=INFO msg="download complete" name=test_file signed_at="2026-10-13 15:38:32.56 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 written=true elapsed=7.711546ms419alpha # [362014.012925] alpha data-mesher[212]: time=2026-10-13T15:38:36.970Z level=DEBUG msg="attempting push/pull" peer_count=2420alpha # [362014.012925] alpha data-mesher[212]: time=2026-10-13T15:38:36.970Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s421alpha # [362014.014540] alpha data-mesher[212]: time=2026-10-13T15:38:36.972Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc422alpha # [362014.014540] alpha data-mesher[212]: time=2026-10-13T15:38:36.972Z level=INFO msg="state exchange complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s423alpha # [362014.014682] alpha data-mesher[212]: time=2026-10-13T15:38:36.972Z level=INFO msg="received state sync from peer" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc424alpha # [362014.014682] alpha data-mesher[212]: time=2026-10-13T15:38:36.972Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc425alpha # [362014.015097] alpha data-mesher[212]: time=2026-10-13T15:38:36.972Z level=INFO msg="received file request" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_file426alpha # [362014.015932] alpha data-mesher[212]: time=2026-10-13T15:38:36.973Z level=DEBUG msg="push/pull successful" interval=5s427alpha # [362014.017451] alpha data-mesher[212]: time=2026-10-13T15:38:36.975Z level=INFO msg="file transfer complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_file428beta # [362014.013297] beta data-mesher[212]: time=2026-10-13T15:38:36.970Z level=DEBUG msg="attempting push/pull" peer_count=2429beta # [362014.013952] beta data-mesher[212]: time=2026-10-13T15:38:36.970Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s430beta # [362014.014351] beta data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc431beta # [362014.014351] beta data-mesher[212]: time=2026-10-13T15:38:36.971Z level=INFO msg="state exchange complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s432beta # [362014.014492] beta data-mesher[212]: time=2026-10-13T15:38:36.972Z level=DEBUG msg="push/pull successful" interval=5s433alpha # [362019.015718] alpha data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="received state sync from peer" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ434gamma # [362019.015247] gamma data-mesher[212]: time=2026-10-13T15:38:41.972Z level=DEBUG msg="attempting push/pull" peer_count=2435alpha # [362019.015718] alpha data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ436gamma # [362019.015247] gamma data-mesher[212]: time=2026-10-13T15:38:41.972Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s437alpha # [362019.016496] alpha data-mesher[212]: time=2026-10-13T15:38:41.973Z level=DEBUG msg="attempting push/pull" peer_count=2438gamma # [362019.016482] gamma data-mesher[212]: time=2026-10-13T15:38:41.974Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ439beta # [362019.014988] beta data-mesher[212]: time=2026-10-13T15:38:41.972Z level=DEBUG msg="attempting push/pull" peer_count=2440gamma # [362019.016482] gamma data-mesher[212]: time=2026-10-13T15:38:41.974Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s441beta # [362019.014988] beta data-mesher[212]: time=2026-10-13T15:38:41.972Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s442gamma # [362019.016609] gamma data-mesher[212]: time=2026-10-13T15:38:41.974Z level=DEBUG msg="push/pull successful" interval=5s443alpha # [362019.016496] alpha data-mesher[212]: time=2026-10-13T15:38:41.973Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s444gamma # [362019.016609] gamma data-mesher[212]: time=2026-10-13T15:38:41.974Z level=INFO msg="received state sync from peer" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2445alpha # [362019.016885] alpha data-mesher[212]: time=2026-10-13T15:38:41.974Z level=INFO msg="received file request" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_file446alpha # [362019.017033] alpha data-mesher[212]: time=2026-10-13T15:38:41.974Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc447beta # [362019.016031] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="received state sync from peer" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc448gamma # [362019.016609] gamma data-mesher[212]: time=2026-10-13T15:38:41.974Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2449alpha # [362019.017100] alpha data-mesher[212]: time=2026-10-13T15:38:41.974Z level=INFO msg="state exchange complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s450beta # [362019.016118] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc451alpha # [362019.017100] alpha data-mesher[212]: time=2026-10-13T15:38:41.974Z level=DEBUG msg="push/pull successful" interval=5s452beta # [362019.016118] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2453alpha # [362019.017463] alpha data-mesher[212]: time=2026-10-13T15:38:41.975Z level=INFO msg="file transfer complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_file454beta # [362019.016223] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=DEBUG msg="new file detected" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 name=test_file name=test_file455beta # [362019.016223] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="state exchange complete" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s456beta # [362019.016223] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=DEBUG msg="push/pull successful" interval=5s457beta # [362019.016223] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=DEBUG msg="new file detected" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc name=test_file name=test_file458beta # [362019.016428] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="scheduling file download" name=test_file459beta # [362019.016428] beta data-mesher[212]: time=2026-10-13T15:38:41.973Z level=INFO msg="downloading file" name=test_file signed_at="2026-10-13 15:38:32.56 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2460beta # [362019.228116] beta data-mesher[212]: time=2026-10-13T15:38:42.185Z level=INFO msg="download complete" name=test_file signed_at="2026-10-13 15:38:32.56 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 written=true elapsed=211.762682ms461beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 10.09 seconds)462gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file463gamma: (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)464beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key465beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.08 seconds)466alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file467beta # [362019.798089] beta data-mesher[212]: time=2026-10-13T15:38:42.755Z level=INFO msg=http_request uri=/files/test_file status=204468gamma # [362024.016962] gamma data-mesher[212]: time=2026-10-13T15:38:46.974Z level=DEBUG msg="attempting push/pull" peer_count=2469gamma # [362024.017663] gamma data-mesher[212]: time=2026-10-13T15:38:46.974Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s470gamma # [362024.017663] gamma data-mesher[212]: time=2026-10-13T15:38:46.974Z level=INFO msg="received state sync from peer" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ471gamma # [362024.017663] gamma data-mesher[212]: time=2026-10-13T15:38:46.974Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ472gamma # [362024.017914] gamma data-mesher[212]: time=2026-10-13T15:38:46.975Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ473gamma # [362024.036400] gamma data-mesher[212]: time=2026-10-13T15:38:46.994Z level=DEBUG msg="imported tombstone" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ name=test_file written=true474gamma # [362024.036524] gamma data-mesher[212]: time=2026-10-13T15:38:46.994Z level=DEBUG msg="imported tombstone" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ name=test_file written=false475gamma # [362024.036524] gamma data-mesher[212]: time=2026-10-13T15:38:46.994Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s476gamma # [362024.036637] gamma data-mesher[212]: time=2026-10-13T15:38:46.994Z level=DEBUG msg="push/pull successful" interval=5s477alpha # [362024.018229] alpha data-mesher[212]: time=2026-10-13T15:38:46.975Z level=DEBUG msg="attempting push/pull" peer_count=2478alpha # [362024.018940] alpha data-mesher[212]: time=2026-10-13T15:38:46.975Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s479alpha # [362024.019176] alpha data-mesher[212]: time=2026-10-13T15:38:46.976Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ480alpha # [362024.036637] alpha data-mesher[212]: time=2026-10-13T15:38:46.994Z level=DEBUG msg="imported tombstone" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ name=test_file written=true481alpha # [362024.036637] alpha data-mesher[212]: time=2026-10-13T15:38:46.994Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s482alpha # [362024.036764] alpha data-mesher[212]: time=2026-10-13T15:38:46.994Z level=DEBUG msg="push/pull successful" interval=5s483beta # [362024.016483] beta data-mesher[212]: time=2026-10-13T15:38:46.974Z level=DEBUG msg="attempting push/pull" peer_count=2484beta # [362024.016483] beta data-mesher[212]: time=2026-10-13T15:38:46.974Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s485beta # [362024.017554] beta data-mesher[212]: time=2026-10-13T15:38:46.975Z level=INFO msg="received state sync from peer" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc486beta # [362024.017554] beta data-mesher[212]: time=2026-10-13T15:38:46.975Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc487beta # [362024.018772] beta data-mesher[212]: time=2026-10-13T15:38:46.976Z level=INFO msg="received state sync from peer" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2488beta # [362024.018772] beta data-mesher[212]: time=2026-10-13T15:38:46.976Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2489beta # [362024.036890] beta data-mesher[212]: time=2026-10-13T15:38:46.994Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc490beta # [362024.037139] beta data-mesher[212]: time=2026-10-13T15:38:46.994Z level=INFO msg="state exchange complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s491beta # [362024.037139] beta data-mesher[212]: time=2026-10-13T15:38:46.994Z level=DEBUG msg="push/pull successful" interval=5s492alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.05 seconds)493beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file494beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)495gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file496gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)497alpha: must succeed: cat /nix/store/2xaqqjqyqd64f5xz66xhgq6lfryam3m4-per-machine-alpha-data-mesher-node-identity_identity.pub498alpha: (finished: must succeed: cat /nix/store/2xaqqjqyqd64f5xz66xhgq6lfryam3m4-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)499alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file500alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)501alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert502alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)503alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk504alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk, in 0.01 seconds)505beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk506alpha # [362024.901252] alpha data-mesher[212]: time=2026-10-13T15:38:47.858Z level=INFO msg=http_request uri=/files/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk status=204507gamma # [362029.036978] gamma data-mesher[212]: time=2026-10-13T15:38:51.994Z level=DEBUG msg="attempting push/pull" peer_count=2508gamma # [362029.036978] gamma data-mesher[212]: time=2026-10-13T15:38:51.994Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s509gamma # [362029.038128] gamma data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ510gamma # [362029.038128] gamma data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s511gamma # [362029.038255] gamma data-mesher[212]: time=2026-10-13T15:38:51.995Z level=DEBUG msg="push/pull successful" interval=5s512alpha # [362029.037774] alpha data-mesher[212]: time=2026-10-13T15:38:51.995Z level=DEBUG msg="attempting push/pull" peer_count=2513alpha # [362029.037774] alpha data-mesher[212]: time=2026-10-13T15:38:51.995Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s514alpha # [362029.038593] alpha data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="received state sync from peer" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ515alpha # [362029.038593] alpha data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ516alpha # [362029.039426] alpha data-mesher[212]: time=2026-10-13T15:38:51.996Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ517alpha # [362029.039768] alpha data-mesher[212]: time=2026-10-13T15:38:51.997Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s518alpha # [362029.039846] alpha data-mesher[212]: time=2026-10-13T15:38:51.997Z level=DEBUG msg="push/pull successful" interval=5s519alpha # [362029.040526] alpha data-mesher[212]: time=2026-10-13T15:38:51.998Z level=INFO msg="received file request" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk520alpha # [362029.042939] alpha data-mesher[212]: time=2026-10-13T15:38:52.000Z level=INFO msg="file transfer complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk521beta # [362029.037322] beta data-mesher[212]: time=2026-10-13T15:38:51.994Z level=DEBUG msg="attempting push/pull" peer_count=2522beta # [362029.037322] beta data-mesher[212]: time=2026-10-13T15:38:51.994Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s523beta # [362029.038152] beta data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="received state sync from peer" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc524beta # [362029.038152] beta data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc525beta # [362029.038288] beta data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="received state sync from peer" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2526beta # [362029.038288] beta data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2527beta # [362029.038394] beta data-mesher[212]: time=2026-10-13T15:38:51.995Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2528beta # [362029.038929] beta data-mesher[212]: time=2026-10-13T15:38:51.996Z level=DEBUG msg="new file detected" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 name=test_file name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk529beta # [362029.039019] beta data-mesher[212]: time=2026-10-13T15:38:51.996Z level=DEBUG msg="new file detected" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 name=test_file name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk530beta # [362029.039019] beta data-mesher[212]: time=2026-10-13T15:38:51.996Z level=INFO msg="state exchange complete" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s531beta # [362029.039118] beta data-mesher[212]: time=2026-10-13T15:38:51.996Z level=INFO msg="scheduling file download" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk532beta # [362029.039118] beta data-mesher[212]: time=2026-10-13T15:38:51.996Z level=DEBUG msg="push/pull successful" interval=5s533beta # [362029.039217] beta data-mesher[212]: time=2026-10-13T15:38:51.996Z level=INFO msg="downloading file" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk signed_at="2026-10-13 15:38:47.855 +0000 UTC" signed_by="1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk=" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2534beta # [362029.044448] beta data-mesher[212]: time=2026-10-13T15:38:52.002Z level=INFO msg="download complete" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk signed_at="2026-10-13 15:38:47.855 +0000 UTC" signed_by="1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk=" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 written=true elapsed=5.338234ms535beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk, in 5.05 seconds)536gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk537gamma # [362034.038454] gamma data-mesher[212]: time=2026-10-13T15:38:56.996Z level=DEBUG msg="attempting push/pull" peer_count=2538gamma # [362034.039120] gamma data-mesher[212]: time=2026-10-13T15:38:56.996Z level=DEBUG msg="initiating state exchange" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s539gamma # [362034.039255] gamma data-mesher[212]: time=2026-10-13T15:38:56.996Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ540gamma # [362034.039663] gamma data-mesher[212]: time=2026-10-13T15:38:56.997Z level=DEBUG msg="new file detected" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ name=test_file name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk541gamma # [362034.039663] gamma data-mesher[212]: time=2026-10-13T15:38:56.997Z level=INFO msg="state exchange complete" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ timeout=5s542gamma # [362034.039779] gamma data-mesher[212]: time=2026-10-13T15:38:56.997Z level=DEBUG msg="push/pull successful" interval=5s543gamma # [362034.039779] gamma data-mesher[212]: time=2026-10-13T15:38:56.997Z level=INFO msg="scheduling file download" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk544gamma # [362034.039881] gamma data-mesher[212]: time=2026-10-13T15:38:56.997Z level=INFO msg="downloading file" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk signed_at="2026-10-13 15:38:47.855 +0000 UTC" signed_by="1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk=" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ545gamma # [362034.041540] gamma data-mesher[212]: time=2026-10-13T15:38:56.999Z level=INFO msg="received state sync from peer" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2546gamma # [362034.041540] gamma data-mesher[212]: time=2026-10-13T15:38:56.999Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2547gamma # [362034.041972] gamma data-mesher[212]: time=2026-10-13T15:38:56.999Z level=DEBUG msg="new file detected" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 name=test_file name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk548gamma # [362034.043864] gamma data-mesher[212]: time=2026-10-13T15:38:57.001Z level=INFO msg="download complete" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk signed_at="2026-10-13 15:38:47.855 +0000 UTC" signed_by="1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk=" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ written=true elapsed=4.048856ms549alpha # [362034.040133] alpha data-mesher[212]: time=2026-10-13T15:38:56.997Z level=INFO msg="received state sync from peer" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ550alpha # [362034.040133] alpha data-mesher[212]: time=2026-10-13T15:38:56.997Z level=INFO msg="merging remote state" peer=12D3KooWEDjJCxpejM7cBFe8oMpnsYEmtyqn9kUR2gN2WPR5GHmZ551alpha # [362034.040605] alpha data-mesher[212]: time=2026-10-13T15:38:56.997Z level=DEBUG msg="attempting push/pull" peer_count=2552alpha # [362034.040605] alpha data-mesher[212]: time=2026-10-13T15:38:56.997Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s553alpha # [362034.042255] alpha data-mesher[212]: time=2026-10-13T15:38:56.999Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc554alpha # [362034.042349] alpha data-mesher[212]: time=2026-10-13T15:38:56.999Z level=INFO msg="state exchange complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc timeout=5s555alpha # [362034.042349] alpha data-mesher[212]: time=2026-10-13T15:38:56.999Z level=DEBUG msg="push/pull successful" interval=5s556beta # [362034.038953] beta data-mesher[212]: time=2026-10-13T15:38:56.996Z level=INFO msg="received state sync from peer" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc557beta # [362034.038953] beta data-mesher[212]: time=2026-10-13T15:38:56.996Z level=INFO msg="merging remote state" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc558beta # [362034.039336] beta data-mesher[212]: time=2026-10-13T15:38:56.996Z level=DEBUG msg="attempting push/pull" peer_count=2559beta # [362034.039336] beta data-mesher[212]: time=2026-10-13T15:38:56.996Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s560beta # [362034.040517] beta data-mesher[212]: time=2026-10-13T15:38:56.997Z level=INFO msg="received file request" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk561beta # [362034.040517] beta data-mesher[212]: time=2026-10-13T15:38:56.997Z level=INFO msg="merging remote state" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2562beta # [362034.042302] beta data-mesher[212]: time=2026-10-13T15:38:56.999Z level=INFO msg="state exchange complete" peer=12D3KooWQB7HBqH7XF38a19civhGDJ7NMKbTAuapQnGtGHyCABM2 timeout=5s563beta # [362034.042416] beta data-mesher[212]: time=2026-10-13T15:38:56.999Z level=DEBUG msg="push/pull successful" interval=5s564beta # [362034.042842] beta data-mesher[212]: time=2026-10-13T15:38:57.000Z level=INFO msg="file transfer complete" peer=12D3KooWNY7sWgFkjyGiof9dvygL4wUFm3xXWpPWVZKyE8PL4rNc network="q53KCnm/uMa9V6NqjSCOdJ+yrTX2TTOgj4C6wBXFFLk=" name=test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk565gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk, in 5.05 seconds)566alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert567alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)568alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub569Error: failed to update file: 403 Forbidden, signer 1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk= is not authorized for this file test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk570alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/ybib5ygc2fjq868d6iql2i514xazln8v-shared-data-mesher-network_network.pub, in 0.03 seconds)571(finished: run the VM test script, in 39.21 seconds)572alpha # [362035.048047] alpha data-mesher[212]: time=2026-10-13T15:38:58.005Z level=INFO msg=http_request uri=/files/test_ns/1VP6oKJzRgL0jKSMlqiNiwBNfoJBY4YnDVvqyQDDlIk status=403573test script finished in 40.51s574cleanup575kill NspawnMachine (pid 52)576kill NspawnMachine (pid 54)577Container alpha terminated by signal KILL.578kill NspawnMachine (pid 56)579Container beta terminated by signal KILL.580Container gamma terminated by signal KILL.581(finished: cleanup, in 0.54 seconds)