container-test-run-data-mesher
checks.aarch64-linux.data-mesher
· build #748
· 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)14gamma: systemd-nspawn running (pid 56)15alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...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(beta): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.22nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE23nixos-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.24nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE25nixos-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.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.27░ Spawning container beta on /build/vm-state-beta.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 alpha on /build/vm-state-alpha.30Note: 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.31░ Spawning container gamma on /build/vm-state-gamma.32alpha # No journal files were found.33alpha # No journal boot entry found for the specified boot (+0).34gamma # No journal files were found.35beta # No journal files were found.36gamma # No journal boot entry found for the specified boot (+0).37beta # No journal boot entry found for the specified boot (+0).38gamma # [1547441.761873] gamma systemd-journald[90]: Journal started39gamma # [1547441.761934] gamma systemd-journald[90]: Runtime Journal (/run/log/journal/08da0c7546b24da783694f6a53f819b1) is 8M, max 2.5G, 2.4G free.40gamma # [1547441.777598] gamma systemd[1]: Finished Apply Kernel Variables.41gamma # [1547441.792551] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.42gamma # [1547441.840560] gamma systemd[1]: Starting Flush Journal to Persistent Storage...43gamma # [1547441.841631] gamma systemd[1]: Starting Network Name Resolution...44gamma # [1547441.842396] gamma systemd[1]: Starting Create Static Device Nodes in /dev...45gamma # [1547441.850625] gamma systemd-journald[90]: Time spent on flushing to /var/log/journal/08da0c7546b24da783694f6a53f819b1 is 3.029ms for 7 entries.46gamma # [1547441.850625] gamma systemd-journald[90]: System Journal (/var/log/journal/08da0c7546b24da783694f6a53f819b1) is 8M, max 4G, 3.9G free.47gamma # [1547441.880972] gamma systemd[1]: Finished Create Static Device Nodes in /dev.48beta # [1547441.770819] beta systemd-journald[90]: Journal started49beta # [1547441.770877] beta systemd-journald[90]: Runtime Journal (/run/log/journal/f79bee09cc7847be85358f029a6bc847) is 8M, max 2.5G, 2.4G free.50beta # [1547441.774111] beta systemd[1]: Finished Apply Kernel Variables.51beta # [1547441.786264] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.52gamma # [1547441.881831] gamma systemd[1]: Reached target Preparation for Local File Systems.53gamma # [1547441.882004] gamma systemd[1]: Reached target Local File Systems.54gamma # [1547441.883266] gamma systemd[1]: Listening on Boot Loader Control Service Socket.55gamma # [1547441.883334] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container56gamma # [1547441.884529] gamma systemd[1]: Starting Save Transient machine-id to Disk...57gamma # [1547441.884584] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys58gamma # [1547441.937086] gamma systemd[1]: Finished Firewall.59gamma # [1547441.938050] gamma systemd[1]: Reached target Preparation for Network.60gamma # [1547441.938610] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.61gamma # [1547441.940714] gamma systemd[1]: Starting Network Management...62gamma # [1547441.969721] gamma systemd[1]: Finished Flush Journal to Persistent Storage.63gamma # [1547441.972248] gamma systemd[1]: Starting Create System Files and Directories...64gamma # [1547441.988031] gamma systemd-tmpfiles[201]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted65gamma # [1547441.988290] gamma systemd-tmpfiles[201]: fchmod() of /var/log/journal failed: Operation not permitted66gamma # [1547441.988473] gamma systemd-tmpfiles[201]: fchmod() of /var/log/journal/08da0c7546b24da783694f6a53f819b1 failed: Operation not permitted67gamma # [1547441.988722] gamma systemd-tmpfiles[201]: fchmod() of /run/log/journal failed: Operation not permitted68gamma # [1547441.990364] gamma systemd[1]: Finished Create System Files and Directories.69gamma # [1547441.991699] gamma systemd[1]: Starting Rebuild Journal Catalog...70gamma # [1547441.992564] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...71gamma # [1547442.007714] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.72gamma # [1547442.017161] gamma systemd[1]: Finished Rebuild Journal Catalog.73gamma # [1547442.018330] gamma systemd[1]: Starting Update is Completed...74gamma # [1547442.029742] gamma systemd[1]: Finished Update is Completed.75gamma # [1547442.640549] gamma systemd[1]: Finished Save Transient machine-id to Disk.76gamma # [1547442.723206] gamma systemd-networkd[197]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted77gamma # [1547442.723307] gamma systemd-networkd[197]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted78beta # [1547441.800397] beta systemd[1]: Starting Flush Journal to Persistent Storage...79beta # [1547441.801272] beta systemd[1]: Starting Network Name Resolution...80beta # [1547441.801976] beta systemd[1]: Starting Create Static Device Nodes in /dev...81beta # [1547441.847117] beta systemd-journald[90]: Time spent on flushing to /var/log/journal/f79bee09cc7847be85358f029a6bc847 is 2.909ms for 7 entries.82beta # [1547441.847117] beta systemd-journald[90]: System Journal (/var/log/journal/f79bee09cc7847be85358f029a6bc847) is 8M, max 4G, 3.9G free.83beta # [1547441.871389] beta systemd[1]: Finished Create Static Device Nodes in /dev.84beta # [1547441.872123] beta systemd[1]: Reached target Preparation for Local File Systems.85beta # [1547441.872243] beta systemd[1]: Reached target Local File Systems.86beta # [1547441.873106] beta systemd[1]: Listening on Boot Loader Control Service Socket.87beta # [1547441.873158] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container88beta # [1547441.874040] beta systemd[1]: Starting Save Transient machine-id to Disk...89beta # [1547441.874079] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys90beta # [1547441.949856] beta systemd[1]: Finished Firewall.91beta # [1547441.950033] beta systemd[1]: Reached target Preparation for Network.92beta # [1547441.950272] beta systemd[1]: Listening on Network Management Resolve Hook Socket.93beta # [1547441.951344] beta systemd[1]: Starting Network Management...94beta # [1547441.968986] beta systemd[1]: Finished Flush Journal to Persistent Storage.95beta # [1547441.971199] beta systemd[1]: Starting Create System Files and Directories...96beta # [1547441.988726] beta systemd-tmpfiles[201]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted97beta # [1547441.988946] beta systemd-tmpfiles[201]: fchmod() of /var/log/journal failed: Operation not permitted98beta # [1547441.989088] beta systemd-tmpfiles[201]: fchmod() of /var/log/journal/f79bee09cc7847be85358f029a6bc847 failed: Operation not permitted99beta # [1547441.989315] beta systemd-tmpfiles[201]: fchmod() of /run/log/journal failed: Operation not permitted100beta # [1547441.990964] beta systemd[1]: Finished Create System Files and Directories.101beta # [1547441.992196] beta systemd[1]: Starting Rebuild Journal Catalog...102beta # [1547441.993180] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...103beta # [1547442.009483] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.104beta # [1547442.013999] beta systemd[1]: Finished Rebuild Journal Catalog.105beta # [1547442.015066] beta systemd[1]: Starting Update is Completed...106beta # [1547442.026686] beta systemd[1]: Finished Update is Completed.107beta # [1547442.643813] beta systemd[1]: Finished Save Transient machine-id to Disk.108alpha # [1547441.762348] alpha systemd-journald[90]: Journal started109alpha # [1547441.762404] alpha systemd-journald[90]: Runtime Journal (/run/log/journal/163b92cb665c47c98d5f22c0d894fd30) is 8M, max 2.5G, 2.4G free.110alpha # [1547441.774116] alpha systemd[1]: Finished Apply Kernel Variables.111alpha # [1547441.787800] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.112alpha # [1547441.802850] alpha systemd[1]: Starting Flush Journal to Persistent Storage...113alpha # [1547441.803641] alpha systemd[1]: Starting Network Name Resolution...114alpha # [1547441.804540] alpha systemd[1]: Starting Create Static Device Nodes in /dev...115alpha # [1547441.847147] alpha systemd-journald[90]: Time spent on flushing to /var/log/journal/163b92cb665c47c98d5f22c0d894fd30 is 1.533ms for 7 entries.116alpha # [1547441.847147] alpha systemd-journald[90]: System Journal (/var/log/journal/163b92cb665c47c98d5f22c0d894fd30) is 8M, max 4G, 3.9G free.117alpha # [1547441.871359] alpha systemd[1]: Finished Create Static Device Nodes in /dev.118alpha # [1547441.872090] alpha systemd[1]: Reached target Preparation for Local File Systems.119alpha # [1547441.872207] alpha systemd[1]: Reached target Local File Systems.120alpha # [1547441.873069] alpha systemd[1]: Listening on Boot Loader Control Service Socket.121alpha # [1547441.873120] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container122alpha # [1547441.874016] alpha systemd[1]: Starting Save Transient machine-id to Disk...123alpha # [1547441.874058] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys124alpha # [1547441.940742] alpha systemd[1]: Finished Firewall.125alpha # [1547441.941503] alpha systemd[1]: Reached target Preparation for Network.126alpha # [1547441.941905] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.127alpha # [1547441.943286] alpha systemd[1]: Starting Network Management...128alpha # [1547441.969366] alpha systemd[1]: Finished Flush Journal to Persistent Storage.129alpha # [1547441.970409] alpha systemd[1]: Starting Create System Files and Directories...130alpha # [1547441.987207] alpha systemd-tmpfiles[201]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted131alpha # [1547441.987495] alpha systemd-tmpfiles[201]: fchmod() of /var/log/journal failed: Operation not permitted132alpha # [1547441.987683] alpha systemd-tmpfiles[201]: fchmod() of /var/log/journal/163b92cb665c47c98d5f22c0d894fd30 failed: Operation not permitted133alpha # [1547441.987934] alpha systemd-tmpfiles[201]: fchmod() of /run/log/journal failed: Operation not permitted134alpha # [1547441.989419] alpha systemd[1]: Finished Create System Files and Directories.135alpha # [1547441.990661] alpha systemd[1]: Starting Rebuild Journal Catalog...136alpha # [1547441.991432] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...137alpha # [1547442.007198] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.138alpha # [1547442.011731] alpha systemd[1]: Finished Rebuild Journal Catalog.139alpha # [1547442.013156] alpha systemd[1]: Starting Update is Completed...140alpha # [1547442.025002] alpha systemd[1]: Finished Update is Completed.141alpha # [1547442.643646] alpha systemd[1]: Finished Save Transient machine-id to Disk.142alpha # [1547442.732223] alpha systemd-networkd[197]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted143alpha # [1547442.732319] alpha systemd-networkd[197]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted144alpha # [1547442.741916] alpha systemd-networkd[197]: /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.145alpha # [1547442.742086] alpha systemd-networkd[197]: /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.146alpha # [1547442.742251] alpha systemd-networkd[197]: lo: Link UP147alpha # [1547442.742257] alpha systemd-networkd[197]: lo: Gained carrier148alpha # [1547442.742430] alpha systemd-networkd[197]: eth1: Configuring with /etc/systemd/network/40-eth1.network.149alpha # [1547442.748794] alpha systemd-networkd[197]: eth1: Link UP150alpha # [1547442.749139] alpha systemd-networkd[197]: eth1: Gained carrier151alpha # [1547442.753068] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.152alpha # [1547442.753784] alpha systemd[1]: Started Network Management.153alpha # [1547442.754889] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...154alpha # [1547442.794004] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.155gamma # [1547442.735551] gamma systemd-networkd[197]: /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.156gamma # [1547442.735724] gamma systemd-networkd[197]: /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.157gamma # [1547442.735887] gamma systemd-networkd[197]: lo: Link UP158gamma # [1547442.735891] gamma systemd-networkd[197]: lo: Gained carrier159gamma # [1547442.736110] gamma systemd-networkd[197]: eth1: Configuring with /etc/systemd/network/40-eth1.network.160gamma # [1547442.736476] gamma systemd[1]: Started Network Management.161gamma # [1547442.748393] gamma systemd-networkd[197]: eth1: Link UP162gamma # [1547442.748833] gamma systemd-networkd[197]: eth1: Gained carrier163gamma # [1547442.748866] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...164gamma # [1547442.762311] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.165gamma # [1547442.798187] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.166beta # [1547442.745046] beta systemd-networkd[197]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted167beta # [1547442.745143] beta systemd-networkd[197]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted168beta # [1547442.754866] beta systemd-networkd[197]: /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.169beta # [1547442.755043] beta systemd-networkd[197]: /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.170beta # [1547442.755215] beta systemd-networkd[197]: lo: Link UP171beta # [1547442.755218] beta systemd-networkd[197]: lo: Gained carrier172beta # [1547442.755423] beta systemd-networkd[197]: eth1: Configuring with /etc/systemd/network/40-eth1.network.173beta # [1547442.755917] beta systemd-networkd[197]: eth1: Link UP174beta # [1547442.756269] beta systemd-networkd[197]: eth1: Gained carrier175beta # [1547442.760762] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.176beta # [1547442.761470] beta systemd[1]: Started Network Management.177beta # [1547442.762817] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...178beta # [1547442.790180] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.179gamma # [1547443.200706] gamma systemd-resolved[144]: Positive Trust Anchors:180gamma # [1547443.200717] gamma systemd-resolved[144]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d181gamma # [1547443.200722] gamma systemd-resolved[144]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16182gamma # [1547443.200756] gamma systemd-resolved[144]: 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 test183gamma # [1547443.223097] gamma systemd-resolved[144]: Using system hostname 'gamma'.184gamma # [1547443.224547] gamma systemd[1]: Started Network Name Resolution.185gamma # [1547443.224620] gamma systemd[1]: Reached target Network.186gamma # [1547443.224675] gamma systemd[1]: Reached target System Initialization.187gamma # [1547443.224727] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container188gamma # [1547443.224753] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.189gamma # [1547443.224769] gamma systemd[1]: Reached target Timer Units.190gamma # [1547443.224887] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.191gamma # [1547443.225018] gamma systemd[1]: Listening on Nix Daemon Socket.192gamma # [1547443.225123] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.193gamma # [1547443.225143] gamma systemd[1]: Reached target Socket Units.194gamma # [1547443.225177] gamma systemd[1]: Reached target Basic System.195gamma # [1547443.269806] gamma systemd[1]: Starting data mesher daemon...196gamma # [1547443.270598] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...197gamma # [1547443.271384] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...198gamma # [1547443.272680] gamma systemd[1]: Starting D-Bus System Message Bus...199gamma # [1547443.290636] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.200beta # [1547443.213958] beta systemd-resolved[120]: Positive Trust Anchors:201beta # [1547443.213968] beta systemd-resolved[120]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d202beta # [1547443.213973] beta systemd-resolved[120]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16203beta # [1547443.214007] beta 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 test204beta # [1547443.235939] beta systemd-resolved[120]: Using system hostname 'beta'.205beta # [1547443.237333] beta systemd[1]: Started Network Name Resolution.206beta # [1547443.237480] beta systemd[1]: Reached target Network.207beta # [1547443.237599] beta systemd[1]: Reached target System Initialization.208beta # [1547443.237699] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container209beta # [1547443.237760] beta systemd[1]: Started Daily Cleanup of Temporary Directories.210beta # [1547443.237798] beta systemd[1]: Reached target Timer Units.211beta # [1547443.238036] beta systemd[1]: Listening on D-Bus System Message Bus Socket.212beta # [1547443.238255] beta systemd[1]: Listening on Nix Daemon Socket.213beta # [1547443.238473] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.214beta # [1547443.238522] beta systemd[1]: Reached target Socket Units.215beta # [1547443.238599] beta systemd[1]: Reached target Basic System.216beta # [1547443.270136] beta systemd[1]: Starting data mesher daemon...217beta # [1547443.271434] beta systemd[1]: Starting Import lastlog data into lastlog2 database...218beta # [1547443.273049] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...219beta # [1547443.275166] beta systemd[1]: Starting D-Bus System Message Bus...220beta # [1547443.291498] beta systemd[1]: Finished Import lastlog data into lastlog2 database.221alpha # [1547443.209918] alpha systemd-resolved[125]: Positive Trust Anchors:222alpha # [1547443.209929] alpha systemd-resolved[125]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d223alpha # [1547443.209933] alpha systemd-resolved[125]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16224alpha # [1547443.209968] alpha systemd-resolved[125]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test225alpha # [1547443.232966] alpha systemd-resolved[125]: Using system hostname 'alpha'.226alpha # [1547443.234396] alpha systemd[1]: Started Network Name Resolution.227alpha # [1547443.234541] alpha systemd[1]: Reached target Network.228alpha # [1547443.234661] alpha systemd[1]: Reached target System Initialization.229alpha # [1547443.234763] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container230alpha # [1547443.234817] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.231alpha # [1547443.234858] alpha systemd[1]: Reached target Timer Units.232alpha # [1547443.235100] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.233alpha # [1547443.235318] alpha systemd[1]: Listening on Nix Daemon Socket.234alpha # [1547443.235542] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.235alpha # [1547443.235590] alpha systemd[1]: Reached target Socket Units.236alpha # [1547443.235673] alpha systemd[1]: Reached target Basic System.237alpha # [1547443.270097] alpha systemd[1]: Starting data mesher daemon...238alpha # [1547443.271355] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...239alpha # [1547443.272729] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...240alpha # [1547443.274879] alpha systemd[1]: Starting D-Bus System Message Bus...241alpha # [1547443.293172] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.242alpha # [1547443.562745] alpha nsncd[215]: Sep 22 05:17:43.547 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"243alpha # [1547443.562228] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).244alpha # [1547443.562287] alpha systemd[1]: Reached target Host and Network Name Lookups.245alpha # [1547443.562351] alpha systemd[1]: Reached target User and Group Name Lookups.246alpha # [1547443.563671] alpha systemd[1]: Starting User Login Management...247alpha # [1547443.565455] alpha systemd[1]: Starting Permit User Sessions...248alpha # [1547443.607518] alpha systemd[1]: Finished Permit User Sessions.249alpha # [1547443.609268] alpha systemd[1]: Started Console Getty.250alpha # [1547443.609351] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0251alpha # [1547443.609398] alpha systemd[1]: Reached target Login Prompts.252alpha # [1547443.763118] alpha dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...253alpha # [1547443.764114] alpha dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'254alpha # [1547443.764114] alpha dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"255alpha # [1547443.764584] alpha systemd[1]: Started D-Bus System Message Bus.256alpha # [1547443.772317] alpha dbus-broker-launch[216]: Ready257beta # [1547443.583205] beta nsncd[215]: Sep 22 05:17:43.568 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"258beta # [1547443.583189] beta systemd[1]: Started Name Service Cache Daemon (nsncd).259beta # [1547443.583292] beta systemd[1]: Reached target Host and Network Name Lookups.260beta # [1547443.583405] beta systemd[1]: Reached target User and Group Name Lookups.261beta # [1547443.600413] beta systemd[1]: Starting User Login Management...262beta # [1547443.601320] beta systemd[1]: Starting Permit User Sessions...263beta # [1547443.612807] beta systemd[1]: Finished Permit User Sessions.264beta # [1547443.614138] beta systemd[1]: Started Console Getty.265beta # [1547443.614192] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0266beta # [1547443.614217] beta systemd[1]: Reached target Login Prompts.267beta # [1547443.763120] beta dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...268beta # [1547443.765369] beta dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'269beta # [1547443.765369] beta dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"270beta # [1547443.765789] beta systemd[1]: Started D-Bus System Message Bus.271beta # [1547443.774922] beta dbus-broker-launch[216]: Ready272gamma # [1547443.574450] gamma nsncd[215]: Sep 22 05:17:43.560 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"273gamma # [1547443.574477] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).274gamma # [1547443.574535] gamma systemd[1]: Reached target Host and Network Name Lookups.275gamma # [1547443.574596] gamma systemd[1]: Reached target User and Group Name Lookups.276gamma # [1547443.600413] gamma systemd[1]: Starting User Login Management...277gamma # [1547443.601268] gamma systemd[1]: Starting Permit User Sessions...278gamma # [1547443.611244] gamma systemd[1]: Finished Permit User Sessions.279gamma # [1547443.612251] gamma systemd[1]: Started Console Getty.280gamma # [1547443.612292] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0281gamma # [1547443.612310] gamma systemd[1]: Reached target Login Prompts.282gamma # [1547443.779413] gamma dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...283gamma # [1547443.780582] gamma dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'284gamma # [1547443.780620] gamma dbus-broker-launch[216]: Invalid user-name in /nix/store/m8v4mzkv0041ab7vjwmmixfphibk9bym-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"285gamma # [1547443.781102] gamma systemd[1]: Started D-Bus System Message Bus.286gamma # [1547443.788078] gamma dbus-broker-launch[216]: Ready287alpha # [1547444.290527] alpha systemd-networkd[197]: eth1: Gained IPv6LL288alpha # [1547444.317112] alpha data-mesher[213]: time=2026-09-22T05:17:44.302Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]289alpha # [1547444.318203] alpha data-mesher[213]: time=2026-09-22T05:17:44.303Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg290alpha # [1547444.318203] alpha data-mesher[213]: time=2026-09-22T05:17:44.304Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml291alpha # [1547444.319357] alpha data-mesher[213]: time=2026-09-22T05:17:44.305Z level=INFO msg="checking file integrity"292alpha # [1547444.319475] alpha data-mesher[213]: time=2026-09-22T05:17:44.305Z level=INFO msg="file integrity check complete"293alpha # [1547444.323334] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="libp2p host created" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.1/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::1/tcp/7946]"294alpha # [1547444.323334] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="registered HTTP route" method=GET path=/files295alpha # [1547444.323467] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name296alpha # [1547444.323467] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name297alpha # [1547444.323467] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="starting server"298alpha # [1547444.323467] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="waiting for DHT to populate" delay=10s299alpha # [1547444.323654] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="HTTP server listening" address=[::1]:7331300alpha # [1547444.323654] alpha data-mesher[213]: time=2026-09-22T05:17:44.309Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331301alpha # [1547444.328095] alpha data-mesher[213]: time=2026-09-22T05:17:44.313Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip4/192.168.1.2/tcp/7946302alpha # [1547444.361686] alpha data-mesher[213]: time=2026-09-22T05:17:44.347Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946303beta # [1547444.308523] beta data-mesher[213]: time=2026-09-22T05:17:44.294Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]304beta # [1547444.309608] beta data-mesher[213]: time=2026-09-22T05:17:44.295Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC305beta # [1547444.309608] beta data-mesher[213]: time=2026-09-22T05:17:44.295Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml306beta # [1547444.310894] beta data-mesher[213]: time=2026-09-22T05:17:44.296Z level=INFO msg="checking file integrity"307beta # [1547444.311031] beta data-mesher[213]: time=2026-09-22T05:17:44.296Z level=INFO msg="file integrity check complete"308beta # [1547444.315285] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="libp2p host created" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.2/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::2/tcp/7946]"309beta # [1547444.315346] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="registered HTTP route" method=GET path=/files310beta # [1547444.315346] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name311beta # [1547444.315346] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name312beta # [1547444.315346] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="starting server"313beta # [1547444.315459] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="waiting for DHT to populate" delay=10s314beta # [1547444.315523] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="HTTP server listening" address=[::1]:7331315beta # [1547444.315579] beta data-mesher[213]: time=2026-09-22T05:17:44.301Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331316beta # [1547444.328758] beta data-mesher[213]: time=2026-09-22T05:17:44.314Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946317beta # [1547444.352223] beta systemd-networkd[197]: eth1: Gained IPv6LL318beta # [1547444.369249] beta data-mesher[213]: time=2026-09-22T05:17:44.355Z level=INFO msg="peer connected" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC remote_addr=/ip4/192.168.1.3/tcp/7946319gamma # [1547444.347970] gamma data-mesher[213]: time=2026-09-22T05:17:44.333Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]320gamma # [1547444.349111] gamma data-mesher[213]: time=2026-09-22T05:17:44.334Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg: [/dns/alpha.clan/tcp/7946]} {12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC321gamma # [1547444.349111] gamma data-mesher[213]: time=2026-09-22T05:17:44.334Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml322gamma # [1547444.351241] gamma data-mesher[213]: time=2026-09-22T05:17:44.337Z level=INFO msg="checking file integrity"323gamma # [1547444.351592] gamma data-mesher[213]: time=2026-09-22T05:17:44.337Z level=INFO msg="file integrity check complete"324gamma # [1547444.355598] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="libp2p host created" peer_id=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC addresses="[/ip4/127.0.0.1/tcp/7946 /ip4/192.168.1.3/tcp/7946 /ip6/::1/tcp/7946 /ip6/2001:db8:1::3/tcp/7946]"325gamma # [1547444.355633] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name326gamma # [1547444.355633] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name327gamma # [1547444.355633] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="registered HTTP route" method=GET path=/files328gamma # [1547444.355693] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="starting server"329gamma # [1547444.355738] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="waiting for DHT to populate" delay=10s330gamma # [1547444.355881] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="HTTP server listening" address=[::1]:7331331gamma # [1547444.355944] gamma data-mesher[213]: time=2026-09-22T05:17:44.341Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331332gamma # [1547444.360718] gamma data-mesher[213]: time=2026-09-22T05:17:44.346Z level=INFO msg="peer connected" peer_id=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg remote_addr=/ip4/192.168.1.1/tcp/7946333gamma # [1547444.368037] gamma data-mesher[213]: time=2026-09-22T05:17:44.353Z level=INFO msg="peer connected" peer_id=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC remote_addr=/ip4/192.168.1.2/tcp/7946334gamma # [1547444.416173] gamma systemd-networkd[197]: eth1: Gained IPv6LL335gamma # [1547444.566243] gamma systemd-logind[232]: New seat seat0.336gamma # [1547444.566482] gamma systemd[1]: Started User Login Management.337gamma # [1547444.568668] gamma systemd[1]: Starting linger-users.service...338alpha # [1547444.575225] alpha systemd-logind[232]: New seat seat0.339alpha # [1547444.576274] alpha systemd[1]: Started User Login Management.340alpha # [1547444.600812] alpha systemd[1]: Starting linger-users.service...341alpha # [1547444.616211] alpha systemd[1]: linger-users.service: Deactivated successfully.342alpha # [1547444.616319] alpha systemd[1]: Finished linger-users.service.343gamma # [1547444.610365] gamma systemd[1]: linger-users.service: Deactivated successfully.344gamma # [1547444.610626] gamma systemd[1]: Finished linger-users.service.345beta # [1547444.575828] beta systemd-logind[232]: New seat seat0.346beta # [1547444.576023] beta systemd[1]: Started User Login Management.347beta # [1547444.600660] beta systemd[1]: Starting linger-users.service...348beta # [1547444.612361] beta systemd[1]: linger-users.service: Deactivated successfully.349beta # [1547444.612571] beta systemd[1]: Finished linger-users.service.350alpha: still waiting for container 'alpha' to reach ready state...351alpha: (finished: waiting for unit data-mesher.service, in 13.68 seconds)352beta: waiting for unit data-mesher.service353beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)354gamma: waiting for unit data-mesher.service355gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)356alpha: must succeed: echo -n 'hello world' > /tmp/test_file357alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)358alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key359alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.05 seconds)360??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.361 File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39362alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file363??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.364 File "/nix/store/z312acj8n2ggn1w3agk97mbvdsm371iw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39365alpha: (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)366beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file367alpha # [1547454.316160] alpha data-mesher[213]: time=2026-09-22T05:17:54.301Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC368alpha # [1547454.316160] alpha data-mesher[213]: time=2026-09-22T05:17:54.301Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC369alpha # [1547454.323893] alpha data-mesher[213]: time=2026-09-22T05:17:54.309Z level=INFO msg="performing state exchange with peers on join" count=1370alpha # [1547454.323993] alpha data-mesher[213]: time=2026-09-22T05:17:54.309Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s371alpha # [1547454.324801] alpha data-mesher[213]: time=2026-09-22T05:17:54.310Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC372alpha # [1547454.324801] alpha data-mesher[213]: time=2026-09-22T05:17:54.310Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s373alpha # [1547454.324863] alpha data-mesher[213]: time=2026-09-22T05:17:54.310Z level=INFO msg="server started"374alpha # [1547454.325098] alpha data-mesher[213]: time=2026-09-22T05:17:54.310Z level=INFO msg="starting expired-file sweeper" interval=1m0s375alpha # [1547454.325663] alpha systemd[1]: Started data mesher daemon.376alpha # [1547454.326176] alpha systemd[1]: Reached target Multi-User System.377alpha # [1547454.326510] alpha systemd[1]: Startup finished in 13.060s.378alpha # [1547454.489072] alpha data-mesher[213]: time=2026-09-22T05:17:54.474Z level=INFO msg=http_request uri=/files/test_file status=204379gamma # [1547454.356382] gamma data-mesher[213]: time=2026-09-22T05:17:54.342Z level=INFO msg="performing state exchange with peers on join" count=1380gamma # [1547454.356812] gamma data-mesher[213]: time=2026-09-22T05:17:54.342Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s381gamma # [1547454.357354] gamma data-mesher[213]: time=2026-09-22T05:17:54.343Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC382gamma # [1547454.357354] gamma data-mesher[213]: time=2026-09-22T05:17:54.343Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s383gamma # [1547454.357452] gamma data-mesher[213]: time=2026-09-22T05:17:54.343Z level=INFO msg="server started"384gamma # [1547454.357517] gamma data-mesher[213]: time=2026-09-22T05:17:54.343Z level=INFO msg="starting expired-file sweeper" interval=1m0s385gamma # [1547454.357690] gamma systemd[1]: Started data mesher daemon.386gamma # [1547454.357991] gamma systemd[1]: Reached target Multi-User System.387gamma # [1547454.358202] gamma systemd[1]: Startup finished in 13.089s.388beta # [1547454.315575] beta data-mesher[213]: time=2026-09-22T05:17:54.301Z level=INFO msg="performing state exchange with peers on join" count=1389beta # [1547454.316332] beta data-mesher[213]: time=2026-09-22T05:17:54.301Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s390beta # [1547454.316332] beta data-mesher[213]: time=2026-09-22T05:17:54.302Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg391beta # [1547454.316454] beta data-mesher[213]: time=2026-09-22T05:17:54.302Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s392beta # [1547454.316454] beta data-mesher[213]: time=2026-09-22T05:17:54.302Z level=INFO msg="server started"393beta # [1547454.316566] beta data-mesher[213]: time=2026-09-22T05:17:54.302Z level=INFO msg="starting expired-file sweeper" interval=1m0s394beta # [1547454.316682] beta systemd[1]: Started data mesher daemon.395beta # [1547454.316981] beta systemd[1]: Reached target Multi-User System.396beta # [1547454.317264] beta systemd[1]: Startup finished in 13.044s.397beta # [1547454.324644] beta data-mesher[213]: time=2026-09-22T05:17:54.310Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg398beta # [1547454.324644] beta data-mesher[213]: time=2026-09-22T05:17:54.310Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg399beta # [1547454.357047] beta data-mesher[213]: time=2026-09-22T05:17:54.342Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC400beta # [1547454.357098] beta data-mesher[213]: time=2026-09-22T05:17:54.342Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC401beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.05 seconds)402gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file403gamma: (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)404beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key405beta # [1547459.317393] beta data-mesher[213]: time=2026-09-22T05:17:59.303Z level=DEBUG msg="attempting push/pull" peer_count=2406beta # [1547459.318068] beta data-mesher[213]: time=2026-09-22T05:17:59.303Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s407beta # [1547459.318223] beta data-mesher[213]: time=2026-09-22T05:17:59.304Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC408beta # [1547459.318223] beta data-mesher[213]: time=2026-09-22T05:17:59.304Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s409beta # [1547459.318335] beta data-mesher[213]: time=2026-09-22T05:17:59.304Z level=DEBUG msg="push/pull successful" interval=5s410beta # [1547459.326056] beta data-mesher[213]: time=2026-09-22T05:17:59.311Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg411beta # [1547459.326056] beta data-mesher[213]: time=2026-09-22T05:17:59.311Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg412beta # [1547459.326231] beta data-mesher[213]: time=2026-09-22T05:17:59.311Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file413beta # [1547459.326231] beta data-mesher[213]: time=2026-09-22T05:17:59.312Z level=INFO msg="scheduling file download" name=test_file414beta # [1547459.326336] beta data-mesher[213]: time=2026-09-22T05:17:59.312Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-22 05:17:54.453 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg415beta # [1547459.381231] beta data-mesher[213]: time=2026-09-22T05:17:59.367Z level=INFO msg="download complete" name=test_file signed_at="2026-09-22 05:17:54.453 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=54.964947ms416beta # [1547459.588135] beta data-mesher[213]: time=2026-09-22T05:17:59.573Z level=INFO msg=http_request uri=/files/test_file status=204417beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)418alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file419alpha # [1547459.325376] alpha data-mesher[213]: time=2026-09-22T05:17:59.311Z level=DEBUG msg="attempting push/pull" peer_count=2420alpha # [1547459.325376] alpha data-mesher[213]: time=2026-09-22T05:17:59.311Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s421alpha # [1547459.326277] alpha data-mesher[213]: time=2026-09-22T05:17:59.312Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC422alpha # [1547459.326277] alpha data-mesher[213]: time=2026-09-22T05:17:59.312Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s423alpha # [1547459.326405] alpha data-mesher[213]: time=2026-09-22T05:17:59.312Z level=DEBUG msg="push/pull successful" interval=5s424alpha # [1547459.326798] alpha data-mesher[213]: time=2026-09-22T05:17:59.312Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file425alpha # [1547459.328885] alpha data-mesher[213]: time=2026-09-22T05:17:59.314Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file426alpha # [1547459.358301] alpha data-mesher[213]: time=2026-09-22T05:17:59.344Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC427alpha # [1547459.358301] alpha data-mesher[213]: time=2026-09-22T05:17:59.344Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC428alpha # [1547459.359087] alpha data-mesher[213]: time=2026-09-22T05:17:59.344Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file429alpha # [1547459.361028] alpha data-mesher[213]: time=2026-09-22T05:17:59.346Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_file430gamma # [1547459.318044] gamma data-mesher[213]: time=2026-09-22T05:17:59.303Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC431gamma # [1547459.318044] gamma data-mesher[213]: time=2026-09-22T05:17:59.303Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC432gamma # [1547459.357753] gamma data-mesher[213]: time=2026-09-22T05:17:59.343Z level=DEBUG msg="attempting push/pull" peer_count=2433gamma # [1547459.357886] gamma data-mesher[213]: time=2026-09-22T05:17:59.343Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s434gamma # [1547459.358560] gamma data-mesher[213]: time=2026-09-22T05:17:59.344Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg435gamma # [1547459.358612] gamma data-mesher[213]: time=2026-09-22T05:17:59.344Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_file436gamma # [1547459.358612] gamma data-mesher[213]: time=2026-09-22T05:17:59.344Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s437gamma # [1547459.358671] gamma data-mesher[213]: time=2026-09-22T05:17:59.344Z level=DEBUG msg="push/pull successful" interval=5s438gamma # [1547459.358701] gamma data-mesher[213]: time=2026-09-22T05:17:59.344Z level=INFO msg="scheduling file download" name=test_file439gamma # [1547459.358738] gamma data-mesher[213]: time=2026-09-22T05:17:59.344Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-22 05:17:54.453 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg440gamma # [1547459.381771] gamma data-mesher[213]: time=2026-09-22T05:17:59.367Z level=INFO msg="download complete" name=test_file signed_at="2026-09-22 05:17:54.453 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=23.027592ms441alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)442beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file443beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)444gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file445gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)446alpha: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub447alpha # [1547464.319441] alpha data-mesher[213]: time=2026-09-22T05:18:04.305Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC448alpha # [1547464.319441] alpha data-mesher[213]: time=2026-09-22T05:18:04.305Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC449alpha # [1547464.326838] alpha data-mesher[213]: time=2026-09-22T05:18:04.312Z level=DEBUG msg="attempting push/pull" peer_count=2450alpha # [1547464.326838] alpha data-mesher[213]: time=2026-09-22T05:18:04.312Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s451alpha # [1547464.327645] alpha data-mesher[213]: time=2026-09-22T05:18:04.313Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC452alpha # [1547464.327740] alpha data-mesher[213]: time=2026-09-22T05:18:04.313Z level=INFO msg="state exchange complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC timeout=5s453alpha # [1547464.327740] alpha data-mesher[213]: time=2026-09-22T05:18:04.313Z level=DEBUG msg="push/pull successful" interval=5s454alpha # [1547464.336784] alpha data-mesher[213]: time=2026-09-22T05:18:04.322Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true455alpha: (finished: must succeed: cat /nix/store/l8fy3mprqpbmza707a3jv7rn5cg55cqq-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)456alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file457alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)458alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert459alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)460alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE461alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 0.01 seconds)462beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE463gamma # [1547464.327391] gamma data-mesher[213]: time=2026-09-22T05:18:04.313Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg464gamma # [1547464.327391] gamma data-mesher[213]: time=2026-09-22T05:18:04.313Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg465gamma # [1547464.359514] gamma data-mesher[213]: time=2026-09-22T05:18:04.345Z level=DEBUG msg="attempting push/pull" peer_count=2466gamma # [1547464.359613] gamma data-mesher[213]: time=2026-09-22T05:18:04.345Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s467gamma # [1547464.360257] gamma data-mesher[213]: time=2026-09-22T05:18:04.346Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC468gamma # [1547464.366527] gamma data-mesher[213]: time=2026-09-22T05:18:04.352Z level=DEBUG msg="imported tombstone" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file written=true469gamma # [1547464.366527] gamma data-mesher[213]: time=2026-09-22T05:18:04.352Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s470gamma # [1547464.366664] gamma data-mesher[213]: time=2026-09-22T05:18:04.352Z level=DEBUG msg="push/pull successful" interval=5s471beta # [1547464.318668] beta data-mesher[213]: time=2026-09-22T05:18:04.304Z level=DEBUG msg="attempting push/pull" peer_count=2472beta # [1547464.318668] beta data-mesher[213]: time=2026-09-22T05:18:04.304Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s473beta # [1547464.337204] beta data-mesher[213]: time=2026-09-22T05:18:04.323Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg474beta # [1547464.337292] beta data-mesher[213]: time=2026-09-22T05:18:04.323Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s475beta # [1547464.337292] beta data-mesher[213]: time=2026-09-22T05:18:04.323Z level=DEBUG msg="push/pull successful" interval=5s476beta # [1547464.359999] beta data-mesher[213]: time=2026-09-22T05:18:04.345Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC477beta # [1547464.360068] beta data-mesher[213]: time=2026-09-22T05:18:04.345Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC478alpha # [1547464.688274] alpha data-mesher[213]: time=2026-09-22T05:18:04.674Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=204479alpha # [1547469.328778] alpha data-mesher[213]: time=2026-09-22T05:18:09.314Z level=DEBUG msg="attempting push/pull" peer_count=2480alpha # [1547469.329164] alpha data-mesher[213]: time=2026-09-22T05:18:09.314Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s481alpha # [1547469.330635] alpha data-mesher[213]: time=2026-09-22T05:18:09.316Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC482alpha # [1547469.330674] alpha data-mesher[213]: time=2026-09-22T05:18:09.316Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s483alpha # [1547469.330737] alpha data-mesher[213]: time=2026-09-22T05:18:09.316Z level=INFO msg="received file request" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE484alpha # [1547469.330765] alpha data-mesher[213]: time=2026-09-22T05:18:09.316Z level=DEBUG msg="push/pull successful" interval=5s485beta # [1547469.329621] beta data-mesher[213]: time=2026-09-22T05:18:09.315Z level=INFO msg="received state sync from peer" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg486alpha # [1547469.332499] alpha data-mesher[213]: time=2026-09-22T05:18:09.318Z level=INFO msg="file transfer complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE487beta # [1547469.329621] beta data-mesher[213]: time=2026-09-22T05:18:09.315Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg488alpha # [1547469.338580] alpha data-mesher[213]: time=2026-09-22T05:18:09.324Z level=INFO msg="received state sync from peer" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC489beta # [1547469.330374] beta data-mesher[213]: time=2026-09-22T05:18:09.316Z level=DEBUG msg="new file detected" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE490alpha # [1547469.338614] alpha data-mesher[213]: time=2026-09-22T05:18:09.324Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC491beta # [1547469.330374] beta data-mesher[213]: time=2026-09-22T05:18:09.316Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE492beta # [1547469.330489] beta data-mesher[213]: time=2026-09-22T05:18:09.316Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 05:18:04.672 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg493beta # [1547469.334559] beta data-mesher[213]: time=2026-09-22T05:18:09.320Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 05:18:04.672 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg written=true elapsed=4.044735ms494beta # [1547469.337928] beta data-mesher[213]: time=2026-09-22T05:18:09.323Z level=DEBUG msg="attempting push/pull" peer_count=2495beta # [1547469.337928] beta data-mesher[213]: time=2026-09-22T05:18:09.323Z level=DEBUG msg="initiating state exchange" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s496beta # [1547469.339617] beta data-mesher[213]: time=2026-09-22T05:18:09.325Z level=INFO msg="merging remote state" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg497beta # [1547469.340270] beta data-mesher[213]: time=2026-09-22T05:18:09.326Z level=INFO msg="state exchange complete" peer=12D3KooWFw5sG68cV874W7eqjFt4bbrJNdMi2U9BtPgDF3TEJsSg timeout=5s498beta # [1547469.340344] beta data-mesher[213]: time=2026-09-22T05:18:09.326Z level=DEBUG msg="push/pull successful" interval=5s499beta # [1547469.368244] beta data-mesher[213]: time=2026-09-22T05:18:09.354Z level=INFO msg="received state sync from peer" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC500beta # [1547469.368244] beta data-mesher[213]: time=2026-09-22T05:18:09.354Z level=INFO msg="merging remote state" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC501beta # [1547469.370154] beta data-mesher[213]: time=2026-09-22T05:18:09.355Z level=INFO msg="received file request" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE502beta # [1547469.371807] beta data-mesher[213]: time=2026-09-22T05:18:09.357Z level=INFO msg="file transfer complete" peer=12D3KooW9tB8rn6Yes5AtcaEnE2K4SU1GavQEktzYyi8P861CfVC network="1g0qqEtcVQyNd9Qjx2U4g++P354YSDGL9EQql7tnxpQ=" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE503gamma # [1547469.367626] gamma data-mesher[213]: time=2026-09-22T05:18:09.353Z level=DEBUG msg="attempting push/pull" peer_count=2504gamma # [1547469.368028] gamma data-mesher[213]: time=2026-09-22T05:18:09.353Z level=DEBUG msg="initiating state exchange" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s505gamma # [1547469.368784] gamma data-mesher[213]: time=2026-09-22T05:18:09.354Z level=INFO msg="merging remote state" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC506gamma # [1547469.369372] gamma data-mesher[213]: time=2026-09-22T05:18:09.355Z level=DEBUG msg="new file detected" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC name=test_file name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE507gamma # [1547469.369401] gamma data-mesher[213]: time=2026-09-22T05:18:09.355Z level=INFO msg="state exchange complete" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC timeout=5s508gamma # [1547469.369453] gamma data-mesher[213]: time=2026-09-22T05:18:09.355Z level=INFO msg="scheduling file download" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE509gamma # [1547469.369518] gamma data-mesher[213]: time=2026-09-22T05:18:09.355Z level=DEBUG msg="push/pull successful" interval=5s510gamma # [1547469.369545] gamma data-mesher[213]: time=2026-09-22T05:18:09.355Z level=INFO msg="downloading file" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 05:18:04.672 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC511gamma # [1547469.373305] gamma data-mesher[213]: time=2026-09-22T05:18:09.359Z level=INFO msg="download complete" name=test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE signed_at="2026-09-22 05:18:04.672 +0000 UTC" signed_by="Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE=" peer=12D3KooWR13z5XBtBndH4BqE5sFAS1jT9S36EK5FaiX3VMJLsSUC written=true elapsed=3.804172ms512beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 5.05 seconds)513gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE514gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE, in 0.01 seconds)515alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert516alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)517alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub518Error: failed to update file: 403 Forbidden, signer Wt7BT+SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE= is not authorized for this file test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE519alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/70h8fdn9fkz8b7ypbc28gw5nsjd8ymdf-shared-data-mesher-network_network.pub, in 0.03 seconds)520(finished: run the VM test script, in 29.08 seconds)521test script finished in 29.33s522cleanup523kill NspawnMachine (pid 53)524alpha # [1547469.796477] alpha data-mesher[213]: time=2026-09-22T05:18:09.782Z level=INFO msg=http_request uri=/files/test_ns/Wt7BT-SAeJ4rzEQztWnNCCLovQNBJzSh0W9fg8WWIRE status=403525kill NspawnMachine (pid 54)526Container alpha terminated by signal KILL.527kill NspawnMachine (pid 56)528Container beta terminated by signal KILL.529Container gamma terminated by signal KILL.530(finished: cleanup, in 0.49 seconds)