container-test-run-data-mesher
default.checks.aarch64-linux.data-mesher
· build #364
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 alpha, beta, gamma,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12alpha: systemd-nspawn running (pid 53)13beta: systemd-nspawn running (pid 54)14alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...15gamma: systemd-nspawn running (pid 56)16beta: Waiting for journal at /build/vm-state-beta/var/log/journal...17gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...18(finished: start all VMs, in 0.00 seconds)19alpha: waiting for unit data-mesher.service20nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE21nixos-nspawn(gamma): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.22nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE23nixos-nspawn(beta): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.24nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE25nixos-nspawn(alpha): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.26Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.27Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.28Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.29░ Spawning container beta on /build/vm-state-beta.30░ Spawning container gamma on /build/vm-state-gamma.31░ Spawning container alpha on /build/vm-state-alpha.32alpha # [5827265.385259] alpha systemd-journald[87]: Journal started33alpha # [5827265.385312] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/361f3cd6937848b3a3d00741e4867f97) is 8M, max 2.5G, 2.4G free.34alpha # [5827265.393277] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.35alpha # [5827265.402318] alpha systemd[1]: Starting Flush Journal to Persistent Storage...36alpha # [5827265.403407] alpha systemd[1]: Starting Network Name Resolution...37alpha # [5827265.404269] alpha systemd[1]: Starting Create Static Device Nodes in /dev...38alpha # [5827265.411686] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/361f3cd6937848b3a3d00741e4867f97 is 1.542ms for 6 entries.39alpha # [5827265.411686] alpha systemd-journald[87]: System Journal (/var/log/journal/361f3cd6937848b3a3d00741e4867f97) is 8M, max 4G, 3.9G free.40alpha # [5827265.420666] alpha systemd[1]: Finished Create Static Device Nodes in /dev.41alpha # [5827265.420913] alpha systemd[1]: Reached target Preparation for Local File Systems.42alpha # [5827265.420997] alpha systemd[1]: Reached target Local File Systems.43alpha # [5827265.421753] alpha systemd[1]: Listening on Boot Loader Control Service Socket.44alpha # [5827265.421801] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container45alpha # [5827265.422629] alpha systemd[1]: Starting Save Transient machine-id to Disk...46alpha # [5827265.422670] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys47alpha # [5827265.423981] alpha systemd[1]: Finished Flush Journal to Persistent Storage.48alpha # [5827265.424924] alpha systemd[1]: Starting Create System Files and Directories...49alpha # [5827265.440335] alpha systemd-tmpfiles[132]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted50alpha # [5827265.440513] alpha systemd-tmpfiles[132]: fchmod() of /var/log/journal failed: Operation not permitted51alpha # [5827265.440632] alpha systemd-tmpfiles[132]: fchmod() of /var/log/journal/361f3cd6937848b3a3d00741e4867f97 failed: Operation not permitted52alpha # [5827265.440814] alpha systemd-tmpfiles[132]: fchmod() of /run/log/journal failed: Operation not permitted53alpha # [5827265.442838] alpha systemd[1]: Finished Create System Files and Directories.54alpha # [5827265.444050] alpha systemd[1]: Starting Rebuild Journal Catalog...55alpha # [5827265.445123] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...56alpha # [5827265.450771] alpha systemd[1]: Finished Save Transient machine-id to Disk.57alpha # [5827265.457215] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.58alpha # [5827265.463152] alpha systemd[1]: Finished Rebuild Journal Catalog.59gamma # [5827265.357067] gamma systemd-journald[87]: Journal started60alpha # [5827265.464287] alpha systemd[1]: Starting Update is Completed...61gamma # [5827265.357118] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/b1da68b823104889a423f33bd2af00e7) is 8M, max 2.5G, 2.4G free.62alpha # [5827265.473664] alpha systemd[1]: Finished Update is Completed.63gamma # [5827265.364190] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.64gamma # [5827265.371989] gamma systemd[1]: Starting Flush Journal to Persistent Storage...65gamma # [5827265.372777] gamma systemd[1]: Starting Network Name Resolution...66gamma # [5827265.373477] gamma systemd[1]: Starting Create Static Device Nodes in /dev...67gamma # [5827265.380683] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/b1da68b823104889a423f33bd2af00e7 is 1.417ms for 6 entries.68gamma # [5827265.380683] gamma systemd-journald[87]: System Journal (/var/log/journal/b1da68b823104889a423f33bd2af00e7) is 8M, max 4G, 3.9G free.69gamma # [5827265.387224] gamma systemd[1]: Finished Create Static Device Nodes in /dev.70gamma # [5827265.387473] gamma systemd[1]: Reached target Preparation for Local File Systems.71gamma # [5827265.387563] gamma systemd[1]: Reached target Local File Systems.72gamma # [5827265.388322] gamma systemd[1]: Listening on Boot Loader Control Service Socket.73gamma # [5827265.388366] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container74gamma # [5827265.389337] gamma systemd[1]: Starting Save Transient machine-id to Disk...75gamma # [5827265.389373] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys76gamma # [5827265.396110] gamma systemd[1]: Finished Flush Journal to Persistent Storage.77gamma # [5827265.397662] gamma systemd[1]: Starting Create System Files and Directories...78gamma # [5827265.415864] gamma systemd-tmpfiles[131]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted79gamma # [5827265.416158] gamma systemd-tmpfiles[131]: fchmod() of /var/log/journal failed: Operation not permitted80gamma # [5827265.416326] gamma systemd-tmpfiles[131]: fchmod() of /var/log/journal/b1da68b823104889a423f33bd2af00e7 failed: Operation not permitted81gamma # [5827265.416565] gamma systemd-tmpfiles[131]: fchmod() of /run/log/journal failed: Operation not permitted82gamma # [5827265.419074] gamma systemd[1]: Finished Create System Files and Directories.83gamma # [5827265.420216] gamma systemd[1]: Starting Rebuild Journal Catalog...84gamma # [5827265.420983] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...85gamma # [5827265.430953] gamma systemd[1]: Finished Save Transient machine-id to Disk.86gamma # [5827265.437198] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.87gamma # [5827265.440250] gamma systemd[1]: Finished Rebuild Journal Catalog.88gamma # [5827265.441266] gamma systemd[1]: Starting Update is Completed...89gamma # [5827265.452947] gamma systemd[1]: Finished Update is Completed.90beta # [5827265.357338] beta systemd-journald[87]: Journal started91beta # [5827265.357390] beta systemd-journald[87]: Runtime Journal (/run/log/journal/780d71556873484481c7ad444871da34) is 8M, max 2.5G, 2.4G free.92beta # [5827265.363744] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.93beta # [5827265.371859] beta systemd[1]: Starting Flush Journal to Persistent Storage...94beta # [5827265.372737] beta systemd[1]: Starting Network Name Resolution...95beta # [5827265.373464] beta systemd[1]: Starting Create Static Device Nodes in /dev...96beta # [5827265.380682] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/780d71556873484481c7ad444871da34 is 1.559ms for 6 entries.97beta # [5827265.380682] beta systemd-journald[87]: System Journal (/var/log/journal/780d71556873484481c7ad444871da34) is 8M, max 4G, 3.9G free.98beta # [5827265.388611] beta systemd[1]: Finished Create Static Device Nodes in /dev.99beta # [5827265.388892] beta systemd[1]: Reached target Preparation for Local File Systems.100beta # [5827265.388982] beta systemd[1]: Reached target Local File Systems.101beta # [5827265.389721] beta systemd[1]: Listening on Boot Loader Control Service Socket.102beta # [5827265.389768] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container103beta # [5827265.390541] beta systemd[1]: Starting Save Transient machine-id to Disk...104beta # [5827265.390580] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys105beta # [5827265.391000] beta systemd[1]: Finished Flush Journal to Persistent Storage.106beta # [5827265.392330] beta systemd[1]: Starting Create System Files and Directories...107beta # [5827265.407896] beta systemd-tmpfiles[128]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted108beta # [5827265.408125] beta systemd-tmpfiles[128]: fchmod() of /var/log/journal failed: Operation not permitted109beta # [5827265.408260] beta systemd-tmpfiles[128]: fchmod() of /var/log/journal/780d71556873484481c7ad444871da34 failed: Operation not permitted110beta # [5827265.408475] beta systemd-tmpfiles[128]: fchmod() of /run/log/journal failed: Operation not permitted111beta # [5827265.410140] beta systemd[1]: Finished Create System Files and Directories.112beta # [5827265.411305] beta systemd[1]: Starting Rebuild Journal Catalog...113beta # [5827265.412173] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...114beta # [5827265.425006] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.115beta # [5827265.431861] beta systemd[1]: Finished Save Transient machine-id to Disk.116beta # [5827265.434264] beta systemd[1]: Finished Rebuild Journal Catalog.117beta # [5827265.435799] beta systemd[1]: Starting Update is Completed...118beta # [5827265.446342] beta systemd[1]: Finished Update is Completed.119beta # [5827265.514455] beta systemd[1]: Finished Firewall.120beta # [5827265.514549] beta systemd[1]: Reached target Preparation for Network.121beta # [5827265.514757] beta systemd[1]: Listening on Network Management Resolve Hook Socket.122beta # [5827265.515743] beta systemd[1]: Starting Network Management...123gamma # [5827265.513971] gamma systemd[1]: Finished Firewall.124gamma # [5827265.514124] gamma systemd[1]: Reached target Preparation for Network.125gamma # [5827265.514343] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.126gamma # [5827265.515406] gamma systemd[1]: Starting Network Management...127alpha # [5827265.540754] alpha systemd[1]: Finished Firewall.128alpha # [5827265.540900] alpha systemd[1]: Reached target Preparation for Network.129alpha # [5827265.541110] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.130alpha # [5827265.542072] alpha systemd[1]: Starting Network Management...131beta # [5827265.912047] beta systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted132beta # [5827265.912143] beta systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted133beta # [5827265.920561] beta systemd-networkd[205]: /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.134beta # [5827265.920867] beta systemd-networkd[205]: /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.135beta # [5827265.921015] beta systemd-networkd[205]: lo: Link UP136alpha # [5827265.934740] alpha systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted137beta # [5827265.921019] beta systemd-networkd[205]: lo: Gained carrier138alpha # [5827265.934833] alpha systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted139alpha # [5827265.941414] alpha systemd-networkd[205]: /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.140alpha # [5827265.941577] alpha systemd-networkd[205]: /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.141alpha # [5827265.941721] alpha systemd-networkd[205]: lo: Link UP142alpha # [5827265.941727] alpha systemd-networkd[205]: lo: Gained carrier143gamma # [5827265.906941] gamma systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted144beta # [5827265.921207] beta systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.145beta # [5827265.921664] beta systemd[1]: Started Network Management.146gamma # [5827265.907041] gamma systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted147gamma # [5827265.917369] gamma systemd-networkd[205]: /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.148gamma # [5827265.917544] gamma systemd-networkd[205]: /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.149gamma # [5827265.917707] gamma systemd-networkd[205]: lo: Link UP150gamma # [5827265.917710] gamma systemd-networkd[205]: lo: Gained carrier151gamma # [5827265.917896] gamma systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.152gamma # [5827265.918319] gamma systemd[1]: Started Network Management.153alpha # [5827265.941924] alpha systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.154alpha # [5827265.942300] alpha systemd[1]: Started Network Management.155alpha # [5827265.964609] alpha systemd-networkd[205]: eth1: Link UP156alpha # [5827265.964799] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...157alpha # [5827265.964899] alpha systemd-networkd[205]: eth1: Gained carrier158alpha # [5827265.998100] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.159alpha # [5827266.108031] alpha systemd-resolved[112]: Positive Trust Anchors:160alpha # [5827266.108043] alpha systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d161alpha # [5827266.108045] alpha systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16162alpha # [5827266.108080] alpha systemd-resolved[112]: 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 test163alpha # [5827266.130406] alpha systemd-resolved[112]: Using system hostname 'alpha'.164alpha # [5827266.131779] alpha systemd[1]: Started Network Name Resolution.165alpha # [5827266.131872] alpha systemd[1]: Reached target Network.166alpha # [5827266.131948] alpha systemd[1]: Reached target System Initialization.167alpha # [5827266.132022] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container168alpha # [5827266.132060] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.169alpha # [5827266.132083] alpha systemd[1]: Reached target Timer Units.170alpha # [5827266.132235] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.171alpha # [5827266.132410] alpha systemd[1]: Listening on Nix Daemon Socket.172alpha # [5827266.132551] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.173alpha # [5827266.132580] alpha systemd[1]: Reached target Socket Units.174alpha # [5827266.132626] alpha systemd[1]: Reached target Basic System.175alpha # [5827266.134150] alpha systemd[1]: Starting data mesher daemon...176alpha # [5827266.135032] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...177alpha # [5827266.135940] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...178alpha # [5827266.137318] alpha systemd[1]: Starting D-Bus System Message Bus...179alpha # [5827266.157670] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.180beta # [5827265.921745] beta systemd-networkd[205]: eth1: Link UP181beta # [5827265.921958] beta systemd-networkd[205]: eth1: Gained carrier182beta # [5827265.922617] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...183beta # [5827265.972830] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.184beta # [5827266.082126] beta systemd-resolved[110]: Positive Trust Anchors:185beta # [5827266.082137] beta systemd-resolved[110]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d186beta # [5827266.082141] beta systemd-resolved[110]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16187beta # [5827266.082175] beta systemd-resolved[110]: 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 test188beta # [5827266.104627] beta systemd-resolved[110]: Using system hostname 'beta'.189beta # [5827266.106012] beta systemd[1]: Started Network Name Resolution.190beta # [5827266.106100] beta systemd[1]: Reached target Network.191beta # [5827266.106171] beta systemd[1]: Reached target System Initialization.192beta # [5827266.106231] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container193beta # [5827266.106262] beta systemd[1]: Started Daily Cleanup of Temporary Directories.194beta # [5827266.106285] beta systemd[1]: Reached target Timer Units.195beta # [5827266.106434] beta systemd[1]: Listening on D-Bus System Message Bus Socket.196beta # [5827266.106575] beta systemd[1]: Listening on Nix Daemon Socket.197beta # [5827266.106714] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.198beta # [5827266.106742] beta systemd[1]: Reached target Socket Units.199beta # [5827266.106786] beta systemd[1]: Reached target Basic System.200beta # [5827266.109082] beta systemd[1]: Starting data mesher daemon...201beta # [5827266.110229] beta systemd[1]: Starting Import lastlog data into lastlog2 database...202beta # [5827266.111128] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...203beta # [5827266.112446] beta systemd[1]: Starting D-Bus System Message Bus...204beta # [5827266.132401] beta systemd[1]: Finished Import lastlog data into lastlog2 database.205gamma # [5827265.918398] gamma systemd-networkd[205]: eth1: Link UP206gamma # [5827265.918665] gamma systemd-networkd[205]: eth1: Gained carrier207gamma # [5827265.919428] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...208gamma # [5827265.972321] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.209gamma # [5827266.081971] gamma systemd-resolved[110]: Positive Trust Anchors:210gamma # [5827266.081985] gamma systemd-resolved[110]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d211gamma # [5827266.081989] gamma systemd-resolved[110]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16212gamma # [5827266.082025] gamma systemd-resolved[110]: 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 test213gamma # [5827266.104629] gamma systemd-resolved[110]: Using system hostname 'gamma'.214gamma # [5827266.106021] gamma systemd[1]: Started Network Name Resolution.215gamma # [5827266.106106] gamma systemd[1]: Reached target Network.216gamma # [5827266.106176] gamma systemd[1]: Reached target System Initialization.217gamma # [5827266.106231] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container218gamma # [5827266.106263] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.219gamma # [5827266.106285] gamma systemd[1]: Reached target Timer Units.220gamma # [5827266.106431] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.221gamma # [5827266.106573] gamma systemd[1]: Listening on Nix Daemon Socket.222gamma # [5827266.106723] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.223gamma # [5827266.106750] gamma systemd[1]: Reached target Socket Units.224gamma # [5827266.106796] gamma systemd[1]: Reached target Basic System.225gamma # [5827266.109081] gamma systemd[1]: Starting data mesher daemon...226gamma # [5827266.110051] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...227gamma # [5827266.110930] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...228gamma # [5827266.112337] gamma systemd[1]: Starting D-Bus System Message Bus...229gamma # [5827266.132367] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.230gamma # [5827266.255751] gamma nsncd[212]: Aug 15 10:04:52.308 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"231gamma # [5827266.255924] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).232gamma # [5827266.256044] gamma systemd[1]: Reached target Host and Network Name Lookups.233gamma # [5827266.256143] gamma systemd[1]: Reached target User and Group Name Lookups.234gamma # [5827266.300735] gamma systemd[1]: Starting User Login Management...235gamma # [5827266.301703] gamma systemd[1]: Starting Permit User Sessions...236gamma # [5827266.310567] gamma dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...237gamma # [5827266.311307] gamma dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'238gamma # [5827266.311307] gamma dbus-broker-launch[213]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"239gamma # [5827266.312094] gamma systemd[1]: Started D-Bus System Message Bus.240gamma # [5827266.319005] gamma dbus-broker-launch[213]: Ready241gamma # [5827266.319778] gamma systemd[1]: Finished Permit User Sessions.242gamma # [5827266.322062] gamma systemd[1]: Started Console Getty.243gamma # [5827266.322166] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0244gamma # [5827266.322290] gamma systemd[1]: Reached target Login Prompts.245gamma # [5827266.349505] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.246alpha # [5827266.255698] alpha nsncd[212]: Aug 15 10:04:52.308 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"247alpha # [5827266.255862] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).248alpha # [5827266.255959] alpha systemd[1]: Reached target Host and Network Name Lookups.249alpha # [5827266.256071] alpha systemd[1]: Reached target User and Group Name Lookups.250alpha # [5827266.300691] alpha systemd[1]: Starting User Login Management...251alpha # [5827266.301504] alpha systemd[1]: Starting Permit User Sessions...252alpha # [5827266.311309] alpha systemd[1]: Finished Permit User Sessions.253alpha # [5827266.313074] alpha systemd[1]: Started Console Getty.254alpha # [5827266.313129] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0255alpha # [5827266.313155] alpha systemd[1]: Reached target Login Prompts.256alpha # [5827266.341939] alpha dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...257alpha # [5827266.342937] alpha dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'258alpha # [5827266.342937] alpha dbus-broker-launch[213]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"259alpha # [5827266.343308] alpha systemd[1]: Started D-Bus System Message Bus.260alpha # [5827266.350647] alpha dbus-broker-launch[213]: Ready261alpha # [5827266.376694] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.262beta # [5827266.250722] beta systemd[1]: Started Name Service Cache Daemon (nsncd).263beta # [5827266.250836] beta systemd[1]: Reached target Host and Network Name Lookups.264beta # [5827266.250940] beta systemd[1]: Reached target User and Group Name Lookups.265beta # [5827266.251117] beta nsncd[212]: Aug 15 10:04:52.304 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"266beta # [5827266.253142] beta systemd[1]: Starting User Login Management...267beta # [5827266.254440] beta systemd[1]: Starting Permit User Sessions...268beta # [5827266.307166] beta systemd[1]: Finished Permit User Sessions.269beta # [5827266.308959] beta systemd[1]: Started Console Getty.270beta # [5827266.309004] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0271beta # [5827266.309022] beta systemd[1]: Reached target Login Prompts.272beta # [5827266.347763] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.273beta # [5827266.350306] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...274beta # [5827266.351145] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'275beta # [5827266.351185] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"276beta # [5827266.351615] beta systemd[1]: Started D-Bus System Message Bus.277beta # [5827266.358363] beta dbus-broker-launch[213]: Ready278gamma # [5827266.571817] gamma data-mesher[210]: time=2026-08-15T10:04:52.624Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]279gamma # [5827266.574911] gamma data-mesher[210]: time=2026-08-15T10:04:52.628Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU: [/dns/alpha.clan/tcp/7946]} {12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS280gamma # [5827266.574911] gamma data-mesher[210]: time=2026-08-15T10:04:52.628Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml281gamma # [5827266.575937] gamma data-mesher[210]: time=2026-08-15T10:04:52.629Z level=INFO msg="checking file integrity"282gamma # [5827266.576084] gamma data-mesher[210]: time=2026-08-15T10:04:52.629Z level=INFO msg="file integrity check complete"283gamma # [5827266.579681] gamma data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="libp2p host created" peer_id=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS 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]"284gamma # [5827266.579746] gamma data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name285gamma # [5827266.579746] gamma data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=GET path=/files286gamma # [5827266.579746] gamma data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name287gamma # [5827266.579746] gamma data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="starting server"288gamma # [5827266.579926] gamma data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="waiting for DHT to populate" delay=10s289gamma # [5827266.579926] gamma data-mesher[210]: time=2026-08-15T10:04:52.633Z level=INFO msg="HTTP server listening" address=[::1]:7331290gamma # [5827266.579926] gamma data-mesher[210]: time=2026-08-15T10:04:52.633Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331291gamma # [5827266.585062] gamma data-mesher[210]: time=2026-08-15T10:04:52.638Z level=INFO msg="peer connected" peer_id=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU remote_addr=/ip4/192.168.1.1/tcp/7946292gamma # [5827266.593105] gamma data-mesher[210]: time=2026-08-15T10:04:52.646Z level=INFO msg="peer connected" peer_id=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u remote_addr=/ip4/192.168.1.2/tcp/7946293gamma # [5827266.593105] gamma data-mesher[210]: time=2026-08-15T10:04:52.647Z level=INFO msg="peer connected" peer_id=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u remote_addr=/ip4/192.168.1.2/tcp/54526294gamma # [5827266.722760] gamma systemd-logind[229]: New seat seat0.295gamma # [5827266.722967] gamma systemd[1]: Started User Login Management.296gamma # [5827266.730030] gamma systemd[1]: Starting linger-users.service...297gamma # [5827266.742529] gamma systemd[1]: linger-users.service: Deactivated successfully.298gamma # [5827266.742692] gamma systemd[1]: Finished linger-users.service.299alpha # [5827266.569461] alpha data-mesher[210]: time=2026-08-15T10:04:52.622Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]300alpha # [5827266.570526] alpha data-mesher[210]: time=2026-08-15T10:04:52.623Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU: [/dns/alpha.clan/tcp/7946]} {12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU301alpha # [5827266.570526] alpha data-mesher[210]: time=2026-08-15T10:04:52.623Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml302alpha # [5827266.575411] alpha data-mesher[210]: time=2026-08-15T10:04:52.628Z level=INFO msg="checking file integrity"303alpha # [5827266.575514] alpha data-mesher[210]: time=2026-08-15T10:04:52.628Z level=INFO msg="file integrity check complete"304alpha # [5827266.579166] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="libp2p host created" peer_id=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU 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]"305alpha # [5827266.579203] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=GET path=/files306alpha # [5827266.579203] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name307alpha # [5827266.579203] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name308alpha # [5827266.579203] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="starting server"309alpha # [5827266.579295] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="waiting for DHT to populate" delay=10s310alpha # [5827266.579390] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="HTTP server listening" address=[::1]:7331311alpha # [5827266.579413] alpha data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331312alpha # [5827266.584580] alpha data-mesher[210]: time=2026-08-15T10:04:52.637Z level=INFO msg="peer connected" peer_id=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u remote_addr=/ip4/192.168.1.2/tcp/7946313alpha # [5827266.586586] alpha data-mesher[210]: time=2026-08-15T10:04:52.639Z level=INFO msg="peer connected" peer_id=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS remote_addr=/ip4/192.168.1.3/tcp/7946314alpha # [5827266.587445] alpha data-mesher[210]: time=2026-08-15T10:04:52.640Z level=INFO msg="peer connected" peer_id=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u remote_addr=/ip4/192.168.1.2/tcp/7946315alpha # [5827266.723141] alpha systemd-logind[229]: New seat seat0.316alpha # [5827266.723375] alpha systemd[1]: Started User Login Management.317alpha # [5827266.729812] alpha systemd[1]: Starting linger-users.service...318alpha # [5827266.743583] alpha systemd[1]: linger-users.service: Deactivated successfully.319alpha # [5827266.743689] alpha systemd[1]: Finished linger-users.service.320beta # [5827266.558115] beta data-mesher[210]: time=2026-08-15T10:04:52.611Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]321beta # [5827266.559191] beta data-mesher[210]: time=2026-08-15T10:04:52.612Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU: [/dns/alpha.clan/tcp/7946]} {12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u322beta # [5827266.559191] beta data-mesher[210]: time=2026-08-15T10:04:52.612Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml323beta # [5827266.575061] beta data-mesher[210]: time=2026-08-15T10:04:52.628Z level=INFO msg="checking file integrity"324beta # [5827266.575174] beta data-mesher[210]: time=2026-08-15T10:04:52.628Z level=INFO msg="file integrity check complete"325beta # [5827266.579041] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="libp2p host created" peer_id=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946]"326beta # [5827266.579087] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=GET path=/files327beta # [5827266.579087] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name328beta # [5827266.579087] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name329beta # [5827266.579139] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="starting server"330beta # [5827266.579200] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="waiting for DHT to populate" delay=10s331beta # [5827266.579597] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="HTTP server listening" address=[::1]:7331332beta # [5827266.579717] beta data-mesher[210]: time=2026-08-15T10:04:52.632Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331333beta # [5827266.585281] beta data-mesher[210]: time=2026-08-15T10:04:52.638Z level=INFO msg="peer connected" peer_id=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU remote_addr=/ip4/192.168.1.1/tcp/7946334beta # [5827266.587018] beta data-mesher[210]: time=2026-08-15T10:04:52.640Z level=INFO msg="peer connected" peer_id=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU remote_addr=/ip4/192.168.1.1/tcp/42560335beta # [5827266.593786] beta data-mesher[210]: time=2026-08-15T10:04:52.646Z level=INFO msg="peer connected" peer_id=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS remote_addr=/ip4/192.168.1.3/tcp/7946336beta # [5827266.594841] beta data-mesher[210]: time=2026-08-15T10:04:52.647Z level=INFO msg="peer connected" peer_id=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS remote_addr=/ip4/192.168.1.3/tcp/7946337beta # [5827266.741870] beta systemd-logind[229]: New seat seat0.338beta # [5827266.742045] beta systemd[1]: Started User Login Management.339beta # [5827266.743780] beta systemd[1]: Starting linger-users.service...340beta # [5827266.757508] beta systemd[1]: linger-users.service: Deactivated successfully.341beta # [5827266.757782] beta systemd[1]: Finished linger-users.service.342gamma # [5827267.424236] gamma systemd-networkd[205]: eth1: Gained IPv6LL343alpha # [5827267.520231] alpha systemd-networkd[205]: eth1: Gained IPv6LL344beta # [5827267.520146] beta systemd-networkd[205]: eth1: Gained IPv6LL345alpha: still waiting for container 'alpha' to reach ready state...346alpha: (finished: waiting for unit data-mesher.service, in 12.16 seconds)347beta: waiting for unit data-mesher.service348beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)349gamma: waiting for unit data-mesher.service350gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)351alpha: must succeed: echo -n 'hello world' > /tmp/test_file352alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)353alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key354alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)355??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.356 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39357alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file358??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.359 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39360alpha: (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)361beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file362gamma # [5827276.580119] gamma data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="performing state exchange with peers on join" count=1363gamma # [5827276.580119] gamma data-mesher[210]: time=2026-08-15T10:05:02.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s364gamma # [5827276.580943] gamma data-mesher[210]: time=2026-08-15T10:05:02.634Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU365gamma # [5827276.580943] gamma data-mesher[210]: time=2026-08-15T10:05:02.634Z level=INFO msg="state exchange complete" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s366gamma # [5827276.581069] gamma data-mesher[210]: time=2026-08-15T10:05:02.634Z level=INFO msg="server started"367gamma # [5827276.581135] gamma data-mesher[210]: time=2026-08-15T10:05:02.634Z level=INFO msg="starting expired-file sweeper" interval=1m0s368gamma # [5827276.581168] gamma systemd[1]: Started data mesher daemon.369gamma # [5827276.581439] gamma systemd[1]: Reached target Multi-User System.370gamma # [5827276.581613] gamma systemd[1]: Startup finished in 11.572s.371alpha # [5827276.579349] alpha data-mesher[210]: time=2026-08-15T10:05:02.632Z level=INFO msg="performing state exchange with peers on join" count=1372alpha # [5827276.579349] alpha data-mesher[210]: time=2026-08-15T10:05:02.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s373alpha # [5827276.580109] alpha data-mesher[210]: time=2026-08-15T10:05:02.632Z level=INFO msg="received state sync from peer" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u374alpha # [5827276.580109] alpha data-mesher[210]: time=2026-08-15T10:05:02.632Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u375alpha # [5827276.580109] alpha data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u376alpha # [5827276.580109] alpha data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="state exchange complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s377alpha # [5827276.580109] alpha data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="server started"378alpha # [5827276.580350] alpha data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="starting expired-file sweeper" interval=1m0s379alpha # [5827276.580239] alpha systemd[1]: Started data mesher daemon.380alpha # [5827276.580472] alpha systemd[1]: Reached target Multi-User System.381alpha # [5827276.580891] alpha data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="received state sync from peer" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS382alpha # [5827276.580891] alpha data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS383alpha # [5827276.580629] alpha systemd[1]: Startup finished in 11.591s.384alpha # [5827276.738264] alpha data-mesher[210]: time=2026-08-15T10:05:02.791Z level=INFO msg=http_request uri=/files/test_file status=204385beta # [5827276.579427] beta data-mesher[210]: time=2026-08-15T10:05:02.632Z level=INFO msg="performing state exchange with peers on join" count=1386beta # [5827276.579427] beta data-mesher[210]: time=2026-08-15T10:05:02.632Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s387beta # [5827276.579926] beta data-mesher[210]: time=2026-08-15T10:05:02.632Z level=INFO msg="received state sync from peer" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU388beta # [5827276.579926] beta data-mesher[210]: time=2026-08-15T10:05:02.632Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU389beta # [5827276.579991] beta data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU390beta # [5827276.579991] beta data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="state exchange complete" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s391beta # [5827276.579991] beta data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="server started"392beta # [5827276.580149] beta data-mesher[210]: time=2026-08-15T10:05:02.633Z level=INFO msg="starting expired-file sweeper" interval=1m0s393beta # [5827276.580245] beta systemd[1]: Started data mesher daemon.394beta # [5827276.580475] beta systemd[1]: Reached target Multi-User System.395beta # [5827276.580627] beta systemd[1]: Startup finished in 11.565s.396beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)397gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file398gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)399beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key400beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)401alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file402gamma # [5827281.583063] gamma data-mesher[210]: time=2026-08-15T10:05:07.636Z level=DEBUG msg="attempting push/pull" peer_count=2403gamma # [5827281.583722] gamma data-mesher[210]: time=2026-08-15T10:05:07.636Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s404gamma # [5827281.583722] gamma data-mesher[210]: time=2026-08-15T10:05:07.636Z level=INFO msg="received state sync from peer" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u405gamma # [5827281.583722] gamma data-mesher[210]: time=2026-08-15T10:05:07.636Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u406gamma # [5827281.584367] gamma data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU407gamma # [5827281.584448] gamma data-mesher[210]: time=2026-08-15T10:05:07.637Z level=DEBUG msg="new file detected" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU name=test_file name=test_file408gamma # [5827281.584448] gamma data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="state exchange complete" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s409gamma # [5827281.584543] gamma data-mesher[210]: time=2026-08-15T10:05:07.637Z level=DEBUG msg="push/pull successful" interval=5s410gamma # [5827281.584543] gamma data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="scheduling file download" name=test_file411gamma # [5827281.584638] gamma data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-15 10:05:02.788 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU412gamma # [5827281.601366] gamma data-mesher[210]: time=2026-08-15T10:05:07.654Z level=INFO msg="download complete" name=test_file signed_at="2026-08-15 10:05:02.788 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU written=true elapsed=16.802318ms413alpha # [5827281.583423] alpha data-mesher[210]: time=2026-08-15T10:05:07.636Z level=DEBUG msg="attempting push/pull" peer_count=2414alpha # [5827281.584088] alpha data-mesher[210]: time=2026-08-15T10:05:07.636Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s415alpha # [5827281.584088] alpha data-mesher[210]: time=2026-08-15T10:05:07.636Z level=INFO msg="received state sync from peer" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS416alpha # [5827281.584088] alpha data-mesher[210]: time=2026-08-15T10:05:07.636Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS417alpha # [5827281.584439] alpha data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u418alpha # [5827281.584439] alpha data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="state exchange complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s419alpha # [5827281.584565] alpha data-mesher[210]: time=2026-08-15T10:05:07.637Z level=DEBUG msg="push/pull successful" interval=5s420alpha # [5827281.584928] alpha data-mesher[210]: time=2026-08-15T10:05:07.638Z level=INFO msg="received file request" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_file421alpha # [5827281.586225] alpha data-mesher[210]: time=2026-08-15T10:05:07.639Z level=INFO msg="received file request" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_file422alpha # [5827281.587729] alpha data-mesher[210]: time=2026-08-15T10:05:07.640Z level=INFO msg="file transfer complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_file423alpha # [5827281.587820] alpha data-mesher[210]: time=2026-08-15T10:05:07.640Z level=INFO msg="file transfer complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_file424beta # [5827281.582901] beta data-mesher[210]: time=2026-08-15T10:05:07.636Z level=DEBUG msg="attempting push/pull" peer_count=2425beta # [5827281.583584] beta data-mesher[210]: time=2026-08-15T10:05:07.636Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s426beta # [5827281.583747] beta data-mesher[210]: time=2026-08-15T10:05:07.636Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS427beta # [5827281.583747] beta data-mesher[210]: time=2026-08-15T10:05:07.636Z level=INFO msg="state exchange complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s428beta # [5827281.583865] beta data-mesher[210]: time=2026-08-15T10:05:07.636Z level=DEBUG msg="push/pull successful" interval=5s429beta # [5827281.584145] beta data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="received state sync from peer" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU430beta # [5827281.584214] beta data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU431beta # [5827281.584214] beta data-mesher[210]: time=2026-08-15T10:05:07.637Z level=DEBUG msg="new file detected" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU name=test_file name=test_file432beta # [5827281.584313] beta data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="scheduling file download" name=test_file433beta # [5827281.584368] beta data-mesher[210]: time=2026-08-15T10:05:07.637Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-15 10:05:02.788 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU434beta # [5827281.601477] beta data-mesher[210]: time=2026-08-15T10:05:07.654Z level=INFO msg="download complete" name=test_file signed_at="2026-08-15 10:05:02.788 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU written=true elapsed=17.125842ms435beta # [5827281.828765] beta data-mesher[210]: time=2026-08-15T10:05:07.881Z level=INFO msg=http_request uri=/files/test_file status=204436gamma # [5827286.585375] gamma data-mesher[210]: time=2026-08-15T10:05:12.638Z level=DEBUG msg="attempting push/pull" peer_count=2437gamma # [5827286.585375] gamma data-mesher[210]: time=2026-08-15T10:05:12.638Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s438gamma # [5827286.586113] gamma data-mesher[210]: time=2026-08-15T10:05:12.638Z level=INFO msg="received state sync from peer" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u439gamma # [5827286.586113] gamma data-mesher[210]: time=2026-08-15T10:05:12.638Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u440gamma # [5827286.586113] gamma data-mesher[210]: time=2026-08-15T10:05:12.638Z level=INFO msg="received state sync from peer" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU441gamma # [5827286.586113] gamma data-mesher[210]: time=2026-08-15T10:05:12.638Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU442gamma # [5827286.586113] gamma data-mesher[210]: time=2026-08-15T10:05:12.639Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU443gamma # [5827286.586113] gamma data-mesher[210]: time=2026-08-15T10:05:12.639Z level=INFO msg="state exchange complete" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s444gamma # [5827286.586402] gamma data-mesher[210]: time=2026-08-15T10:05:12.639Z level=DEBUG msg="push/pull successful" interval=5s445gamma # [5827286.676707] gamma data-mesher[210]: time=2026-08-15T10:05:12.729Z level=DEBUG msg="imported tombstone" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u name=test_file written=true446alpha # [5827286.585312] alpha data-mesher[210]: time=2026-08-15T10:05:12.638Z level=DEBUG msg="attempting push/pull" peer_count=2447alpha # [5827286.586001] alpha data-mesher[210]: time=2026-08-15T10:05:12.638Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s448alpha # [5827286.586001] alpha data-mesher[210]: time=2026-08-15T10:05:12.638Z level=INFO msg="received state sync from peer" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS449alpha # [5827286.586001] alpha data-mesher[210]: time=2026-08-15T10:05:12.638Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS450alpha # [5827286.586001] alpha data-mesher[210]: time=2026-08-15T10:05:12.639Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS451alpha # [5827286.586001] alpha data-mesher[210]: time=2026-08-15T10:05:12.639Z level=INFO msg="state exchange complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s452alpha # [5827286.586248] alpha data-mesher[210]: time=2026-08-15T10:05:12.639Z level=DEBUG msg="push/pull successful" interval=5s453beta # [5827286.584890] beta data-mesher[210]: time=2026-08-15T10:05:12.638Z level=DEBUG msg="attempting push/pull" peer_count=2454beta # [5827286.585270] beta data-mesher[210]: time=2026-08-15T10:05:12.638Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s455beta # [5827286.677181] beta data-mesher[210]: time=2026-08-15T10:05:12.730Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS456beta # [5827286.677375] beta data-mesher[210]: time=2026-08-15T10:05:12.730Z level=INFO msg="state exchange complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s457beta # [5827286.677375] beta data-mesher[210]: time=2026-08-15T10:05:12.730Z level=DEBUG msg="push/pull successful" interval=5s458alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.07 seconds)459beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file460alpha # [5827291.586453] alpha data-mesher[210]: time=2026-08-15T10:05:17.639Z level=DEBUG msg="attempting push/pull" peer_count=2461alpha # [5827291.586453] alpha data-mesher[210]: time=2026-08-15T10:05:17.639Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s462alpha # [5827291.587040] alpha data-mesher[210]: time=2026-08-15T10:05:17.640Z level=INFO msg="received state sync from peer" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS463alpha # [5827291.587076] alpha data-mesher[210]: time=2026-08-15T10:05:17.640Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS464alpha # [5827291.587184] alpha data-mesher[210]: time=2026-08-15T10:05:17.640Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u465alpha # [5827291.833329] alpha data-mesher[210]: time=2026-08-15T10:05:17.886Z level=DEBUG msg="imported tombstone" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS name=test_file written=true466alpha # [5827291.833455] alpha data-mesher[210]: time=2026-08-15T10:05:17.886Z level=DEBUG msg="imported tombstone" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u name=test_file written=false467alpha # [5827291.833455] alpha data-mesher[210]: time=2026-08-15T10:05:17.886Z level=INFO msg="state exchange complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s468alpha # [5827291.833455] alpha data-mesher[210]: time=2026-08-15T10:05:17.886Z level=DEBUG msg="push/pull successful" interval=5s469beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)470gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file471gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.00 seconds)472alpha: must succeed: cat /nix/store/p9ajxnwzpw7hf0isza04i5dc122djg3h-per-machine-alpha-data-mesher-node-identity_identity.pub473alpha: (finished: must succeed: cat /nix/store/p9ajxnwzpw7hf0isza04i5dc122djg3h-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)474alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file475alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)476alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert477alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)478alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU479alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU, in 0.01 seconds)480beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU481beta # [5827291.586949] beta data-mesher[210]: time=2026-08-15T10:05:17.640Z level=INFO msg="received state sync from peer" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU482beta # [5827291.586949] beta data-mesher[210]: time=2026-08-15T10:05:17.640Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU483beta # [5827291.678124] beta data-mesher[210]: time=2026-08-15T10:05:17.731Z level=DEBUG msg="attempting push/pull" peer_count=2484beta # [5827291.678197] beta data-mesher[210]: time=2026-08-15T10:05:17.731Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s485beta # [5827291.678762] beta data-mesher[210]: time=2026-08-15T10:05:17.731Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS486beta # [5827291.678798] beta data-mesher[210]: time=2026-08-15T10:05:17.731Z level=INFO msg="state exchange complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s487beta # [5827291.678826] beta data-mesher[210]: time=2026-08-15T10:05:17.731Z level=DEBUG msg="push/pull successful" interval=5s488gamma # [5827291.586530] gamma data-mesher[210]: time=2026-08-15T10:05:17.639Z level=DEBUG msg="attempting push/pull" peer_count=2489gamma # [5827291.586945] gamma data-mesher[210]: time=2026-08-15T10:05:17.639Z level=DEBUG msg="initiating state exchange" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s490gamma # [5827291.678578] gamma data-mesher[210]: time=2026-08-15T10:05:17.731Z level=INFO msg="received state sync from peer" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u491gamma # [5827291.678578] gamma data-mesher[210]: time=2026-08-15T10:05:17.731Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u492gamma # [5827291.833620] gamma data-mesher[210]: time=2026-08-15T10:05:17.886Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU493gamma # [5827291.833620] gamma data-mesher[210]: time=2026-08-15T10:05:17.886Z level=INFO msg="state exchange complete" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU timeout=5s494gamma # [5827291.833749] gamma data-mesher[210]: time=2026-08-15T10:05:17.886Z level=DEBUG msg="push/pull successful" interval=5s495alpha # [5827291.951854] alpha data-mesher[210]: time=2026-08-15T10:05:18.004Z level=INFO msg=http_request uri=/files/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU status=204496beta # [5827296.679667] beta data-mesher[210]: time=2026-08-15T10:05:22.732Z level=DEBUG msg="attempting push/pull" peer_count=2497beta # [5827296.680196] beta data-mesher[210]: time=2026-08-15T10:05:22.732Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s498beta # [5827296.680763] beta data-mesher[210]: time=2026-08-15T10:05:22.733Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS499beta # [5827296.680763] beta data-mesher[210]: time=2026-08-15T10:05:22.733Z level=INFO msg="state exchange complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s500beta # [5827296.680763] beta data-mesher[210]: time=2026-08-15T10:05:22.733Z level=DEBUG msg="push/pull successful" interval=5s501beta # [5827296.835194] beta data-mesher[210]: time=2026-08-15T10:05:22.888Z level=INFO msg="received state sync from peer" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS502beta # [5827296.835194] beta data-mesher[210]: time=2026-08-15T10:05:22.888Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS503beta # [5827296.835194] beta data-mesher[210]: time=2026-08-15T10:05:22.888Z level=INFO msg="received state sync from peer" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU504beta # [5827296.835447] beta data-mesher[210]: time=2026-08-15T10:05:22.888Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU505beta # [5827296.835872] beta data-mesher[210]: time=2026-08-15T10:05:22.889Z level=DEBUG msg="new file detected" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU name=test_file name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU506beta # [5827296.836065] beta data-mesher[210]: time=2026-08-15T10:05:22.889Z level=INFO msg="scheduling file download" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU507beta # [5827296.836185] beta data-mesher[210]: time=2026-08-15T10:05:22.889Z level=INFO msg="downloading file" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU signed_at="2026-08-15 10:05:18.003 +0000 UTC" signed_by="PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU=" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU508beta # [5827296.839182] beta data-mesher[210]: time=2026-08-15T10:05:22.892Z level=INFO msg="download complete" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU signed_at="2026-08-15 10:05:18.003 +0000 UTC" signed_by="PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU=" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU written=true elapsed=3.099804ms509beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU, in 5.04 seconds)510gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU511gamma # [5827296.680246] gamma data-mesher[210]: time=2026-08-15T10:05:22.733Z level=INFO msg="received state sync from peer" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u512gamma # [5827296.680246] gamma data-mesher[210]: time=2026-08-15T10:05:22.733Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u513gamma # [5827296.834242] gamma data-mesher[210]: time=2026-08-15T10:05:22.887Z level=DEBUG msg="attempting push/pull" peer_count=2514gamma # [5827296.834337] gamma data-mesher[210]: time=2026-08-15T10:05:22.887Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s515gamma # [5827296.835666] gamma data-mesher[210]: time=2026-08-15T10:05:22.888Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u516gamma # [5827296.835730] gamma data-mesher[210]: time=2026-08-15T10:05:22.888Z level=INFO msg="state exchange complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s517gamma # [5827296.835782] gamma data-mesher[210]: time=2026-08-15T10:05:22.888Z level=DEBUG msg="push/pull successful" interval=5s518alpha # [5827296.834314] alpha data-mesher[210]: time=2026-08-15T10:05:22.887Z level=DEBUG msg="attempting push/pull" peer_count=2519alpha # [5827296.835034] alpha data-mesher[210]: time=2026-08-15T10:05:22.887Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s520alpha # [5827296.836246] alpha data-mesher[210]: time=2026-08-15T10:05:22.889Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u521alpha # [5827296.836246] alpha data-mesher[210]: time=2026-08-15T10:05:22.889Z level=INFO msg="state exchange complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s522alpha # [5827296.836449] alpha data-mesher[210]: time=2026-08-15T10:05:22.889Z level=DEBUG msg="push/pull successful" interval=5s523alpha # [5827296.836596] alpha data-mesher[210]: time=2026-08-15T10:05:22.889Z level=INFO msg="received file request" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU524alpha # [5827296.837003] alpha data-mesher[210]: time=2026-08-15T10:05:22.890Z level=INFO msg="file transfer complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU525beta # [5827301.681457] beta data-mesher[210]: time=2026-08-15T10:05:27.734Z level=DEBUG msg="attempting push/pull" peer_count=2526beta # [5827301.681457] beta data-mesher[210]: time=2026-08-15T10:05:27.734Z level=DEBUG msg="initiating state exchange" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s527beta # [5827301.683386] beta data-mesher[210]: time=2026-08-15T10:05:27.736Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS528beta # [5827301.683470] beta data-mesher[210]: time=2026-08-15T10:05:27.736Z level=INFO msg="state exchange complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS timeout=5s529beta # [5827301.683470] beta data-mesher[210]: time=2026-08-15T10:05:27.736Z level=INFO msg="received file request" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU530beta # [5827301.683470] beta data-mesher[210]: time=2026-08-15T10:05:27.736Z level=DEBUG msg="push/pull successful" interval=5s531beta # [5827301.686118] beta data-mesher[210]: time=2026-08-15T10:05:27.739Z level=INFO msg="file transfer complete" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS network="erW0ZZXCb6oG+awgxvx11iKi5hIA3yp3THMk4fyJKfY=" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU532beta # [5827301.836858] beta data-mesher[210]: time=2026-08-15T10:05:27.890Z level=INFO msg="received state sync from peer" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS533beta # [5827301.836858] beta data-mesher[210]: time=2026-08-15T10:05:27.890Z level=INFO msg="merging remote state" peer=12D3KooWCAGw4M1VsvojJQkdX8Uagw1TN9pQ48GuxcQbUmVQG5hS534beta # [5827301.838065] beta data-mesher[210]: time=2026-08-15T10:05:27.891Z level=INFO msg="received state sync from peer" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU535beta # [5827301.838108] beta data-mesher[210]: time=2026-08-15T10:05:27.891Z level=INFO msg="merging remote state" peer=12D3KooWE4aBFe5CVrnqDneNnqVGkhGV2gZzqppAymGhFnqmoMiU536gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU, in 5.04 seconds)537alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert538alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)539alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub540Error: failed to update file: 403 Forbidden, signer PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU= is not authorized for this file test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU541alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4mmqaqw3a5v2xvrylawbxaz61vx2yf9q-shared-data-mesher-network_network.pub, in 0.03 seconds)542(finished: run the VM test script, in 37.58 seconds)543gamma # [5827301.682120] gamma data-mesher[210]: time=2026-08-15T10:05:27.735Z level=INFO msg="received state sync from peer" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u544gamma # [5827301.682120] gamma data-mesher[210]: time=2026-08-15T10:05:27.735Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u545gamma # [5827301.682760] gamma data-mesher[210]: time=2026-08-15T10:05:27.735Z level=DEBUG msg="new file detected" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u name=test_file name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU546gamma # [5827301.682851] gamma data-mesher[210]: time=2026-08-15T10:05:27.736Z level=INFO msg="scheduling file download" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU547gamma # [5827301.682914] gamma data-mesher[210]: time=2026-08-15T10:05:27.736Z level=INFO msg="downloading file" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU signed_at="2026-08-15 10:05:18.003 +0000 UTC" signed_by="PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU=" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u548gamma # [5827301.688744] gamma data-mesher[210]: time=2026-08-15T10:05:27.741Z level=INFO msg="download complete" name=test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU signed_at="2026-08-15 10:05:18.003 +0000 UTC" signed_by="PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU=" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u written=true elapsed=5.798562ms549gamma # [5827301.836191] gamma data-mesher[210]: time=2026-08-15T10:05:27.889Z level=DEBUG msg="attempting push/pull" peer_count=2550gamma # [5827301.836191] gamma data-mesher[210]: time=2026-08-15T10:05:27.889Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s551gamma # [5827301.838182] gamma data-mesher[210]: time=2026-08-15T10:05:27.890Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u552gamma # [5827301.838182] gamma data-mesher[210]: time=2026-08-15T10:05:27.891Z level=INFO msg="state exchange complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s553gamma # [5827301.838182] gamma data-mesher[210]: time=2026-08-15T10:05:27.891Z level=DEBUG msg="push/pull successful" interval=5s554alpha # [5827301.837454] alpha data-mesher[210]: time=2026-08-15T10:05:27.890Z level=DEBUG msg="attempting push/pull" peer_count=2555alpha # [5827301.838124] alpha data-mesher[210]: time=2026-08-15T10:05:27.890Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s556alpha # [5827301.838999] alpha data-mesher[210]: time=2026-08-15T10:05:27.892Z level=INFO msg="merging remote state" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u557alpha # [5827301.839329] alpha data-mesher[210]: time=2026-08-15T10:05:27.892Z level=INFO msg="state exchange complete" peer=12D3KooWBHfTbq8jgEyTdDjdK5hucHTZ7PRSquDpFADRkRuf8E9u timeout=5s558alpha # [5827301.839394] alpha data-mesher[210]: time=2026-08-15T10:05:27.892Z level=DEBUG msg="push/pull successful" interval=5s559alpha # [5827302.082355] alpha data-mesher[210]: time=2026-08-15T10:05:28.135Z level=INFO msg=http_request uri=/files/test_ns/PxJ1F8cSCoBFAuRZctXH0oFDgCFQCf3ePFUj9167ymU status=403560test script finished in 38.42s561cleanup562kill NspawnMachine (pid 53)563kill NspawnMachine (pid 54)564Container alpha terminated by signal KILL.565kill NspawnMachine (pid 56)566Container beta terminated by signal KILL.567(finished: cleanup, in 0.54 seconds)568Container gamma terminated by signal KILL.