container-test-run-data-mesher log

succeeded `container-test-run-data-mesher` `checks.aarch64-linux.data-mesher` · build #549 · 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)13gamma: systemd-nspawn running (pid 54)14beta: systemd-nspawn running (pid 55)15alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...16gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...17beta: Waiting for journal at /build/vm-state-beta/var/log/journal...18(finished: start all VMs, in 0.00 seconds)19alpha: waiting for unit data-mesher.service20nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.22nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE23nixos-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.24nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE25nixos-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.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 alpha on /build/vm-state-alpha.30░ Spawning container beta on /build/vm-state-beta.31░ Spawning container gamma on /build/vm-state-gamma.32gamma # [7346688.333732] gamma systemd-journald[87]: Journal started33alpha # [7346688.347517] alpha systemd-journald[87]: Journal started34gamma # [7346688.333785] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/0827ef8a64b74c5084bb58a5e17874c1) is 8M, max 2.5G, 2.4G free.35alpha # [7346688.347574] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/04bd2fb594614173967e6f25e895ef0b) is 8M, max 2.5G, 2.4G free.36gamma # [7346688.339091] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.37alpha # [7346688.353176] alpha systemd[1]: Starting Flush Journal to Persistent Storage...38gamma # [7346688.347018] gamma systemd[1]: Starting Flush Journal to Persistent Storage...39alpha # [7346688.353936] alpha systemd[1]: Starting Network Name Resolution...40gamma # [7346688.347833] gamma systemd[1]: Starting Network Name Resolution...41alpha # [7346688.354614] alpha systemd[1]: Starting Create Static Device Nodes in /dev...42gamma # [7346688.348499] gamma systemd[1]: Starting Create Static Device Nodes in /dev...43alpha # [7346688.363949] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/04bd2fb594614173967e6f25e895ef0b is 1.127ms for 5 entries.44gamma # [7346688.355430] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/0827ef8a64b74c5084bb58a5e17874c1 is 1.034ms for 6 entries.45alpha # [7346688.363949] alpha systemd-journald[87]: System Journal (/var/log/journal/04bd2fb594614173967e6f25e895ef0b) is 8M, max 4G, 3.9G free.46gamma # [7346688.355430] gamma systemd-journald[87]: System Journal (/var/log/journal/0827ef8a64b74c5084bb58a5e17874c1) is 8M, max 4G, 3.9G free.47alpha # [7346688.371262] alpha systemd[1]: Finished Create Static Device Nodes in /dev.48gamma # [7346688.364213] gamma systemd[1]: Finished Create Static Device Nodes in /dev.49alpha # [7346688.371880] alpha systemd[1]: Reached target Preparation for Local File Systems.50gamma # [7346688.364459] gamma systemd[1]: Reached target Preparation for Local File Systems.51alpha # [7346688.371987] alpha systemd[1]: Reached target Local File Systems.52gamma # [7346688.364551] gamma systemd[1]: Reached target Local File Systems.53alpha # [7346688.372979] alpha systemd[1]: Listening on Boot Loader Control Service Socket.54gamma # [7346688.365293] gamma systemd[1]: Listening on Boot Loader Control Service Socket.55alpha # [7346688.373025] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container56gamma # [7346688.365335] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container57alpha # [7346688.373804] alpha systemd[1]: Starting Save Transient machine-id to Disk...58gamma # [7346688.366217] gamma systemd[1]: Starting Save Transient machine-id to Disk...59alpha # [7346688.373839] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys60gamma # [7346688.366247] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys61alpha # [7346688.378172] alpha systemd[1]: Finished Flush Journal to Persistent Storage.62gamma # [7346688.370524] gamma systemd[1]: Finished Flush Journal to Persistent Storage.63alpha # [7346688.379095] alpha systemd[1]: Starting Create System Files and Directories...64beta # [7346688.334339] beta systemd-journald[87]: Journal started65alpha # [7346688.395244] alpha systemd-tmpfiles[129]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted66beta # [7346688.334391] beta systemd-journald[87]: Runtime Journal (/run/log/journal/3546fedc400d420fa24d6d1129c01a5c) is 8M, max 2.5G, 2.4G free.67alpha # [7346688.395470] alpha systemd-tmpfiles[129]: fchmod() of /var/log/journal failed: Operation not permitted68beta # [7346688.340204] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.69alpha # [7346688.395627] alpha systemd-tmpfiles[129]: fchmod() of /var/log/journal/04bd2fb594614173967e6f25e895ef0b failed: Operation not permitted70beta # [7346688.349244] beta systemd[1]: Starting Flush Journal to Persistent Storage...71alpha # [7346688.395888] alpha systemd-tmpfiles[129]: fchmod() of /run/log/journal failed: Operation not permitted72beta # [7346688.350055] beta systemd[1]: Starting Network Name Resolution...73alpha # [7346688.397471] alpha systemd[1]: Finished Create System Files and Directories.74beta # [7346688.350709] beta systemd[1]: Starting Create Static Device Nodes in /dev...75alpha # [7346688.398877] alpha systemd[1]: Starting Rebuild Journal Catalog...76beta # [7346688.360073] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/3546fedc400d420fa24d6d1129c01a5c is 1.065ms for 6 entries.77alpha # [7346688.399667] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...78beta # [7346688.360073] beta systemd-journald[87]: System Journal (/var/log/journal/3546fedc400d420fa24d6d1129c01a5c) is 8M, max 4G, 3.9G free.79alpha # [7346688.411739] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.80beta # [7346688.364294] beta systemd[1]: Finished Create Static Device Nodes in /dev.81alpha # [7346688.422207] alpha systemd[1]: Finished Rebuild Journal Catalog.82beta # [7346688.364552] beta systemd[1]: Reached target Preparation for Local File Systems.83alpha # [7346688.423209] alpha systemd[1]: Starting Update is Completed...84beta # [7346688.364638] beta systemd[1]: Reached target Local File Systems.85gamma # [7346688.371485] gamma systemd[1]: Starting Create System Files and Directories...86beta # [7346688.365342] beta systemd[1]: Listening on Boot Loader Control Service Socket.87gamma # [7346688.389053] gamma systemd-tmpfiles[127]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted88beta # [7346688.365379] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container89gamma # [7346688.389261] gamma systemd-tmpfiles[127]: fchmod() of /var/log/journal failed: Operation not permitted90beta # [7346688.366209] beta systemd[1]: Starting Save Transient machine-id to Disk...91gamma # [7346688.389405] gamma systemd-tmpfiles[127]: fchmod() of /var/log/journal/0827ef8a64b74c5084bb58a5e17874c1 failed: Operation not permitted92beta # [7346688.366245] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys93gamma # [7346688.389623] gamma systemd-tmpfiles[127]: fchmod() of /run/log/journal failed: Operation not permitted94beta # [7346688.371811] beta systemd[1]: Finished Flush Journal to Persistent Storage.95gamma # [7346688.391137] gamma systemd[1]: Finished Create System Files and Directories.96beta # [7346688.373486] beta systemd[1]: Starting Create System Files and Directories...97gamma # [7346688.392296] gamma systemd[1]: Starting Rebuild Journal Catalog...98beta # [7346688.390058] beta systemd-tmpfiles[129]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted99gamma # [7346688.393073] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...100beta # [7346688.390265] beta systemd-tmpfiles[129]: fchmod() of /var/log/journal failed: Operation not permitted101gamma # [7346688.406775] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.102beta # [7346688.390405] beta systemd-tmpfiles[129]: fchmod() of /var/log/journal/3546fedc400d420fa24d6d1129c01a5c failed: Operation not permitted103gamma # [7346688.413121] gamma systemd[1]: Finished Rebuild Journal Catalog.104beta # [7346688.390624] beta systemd-tmpfiles[129]: fchmod() of /run/log/journal failed: Operation not permitted105gamma # [7346688.414104] gamma systemd[1]: Starting Update is Completed...106beta # [7346688.391950] beta systemd[1]: Finished Create System Files and Directories.107gamma # [7346688.424338] gamma systemd[1]: Finished Update is Completed.108beta # [7346688.393195] beta systemd[1]: Starting Rebuild Journal Catalog...109beta # [7346688.393857] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...110beta # [7346688.407266] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.111beta # [7346688.410869] beta systemd[1]: Finished Rebuild Journal Catalog.112beta # [7346688.412724] beta systemd[1]: Starting Update is Completed...113beta # [7346688.423960] beta systemd[1]: Finished Update is Completed.114beta # [7346688.488192] beta systemd[1]: Finished Firewall.115beta # [7346688.488381] beta systemd[1]: Reached target Preparation for Network.116beta # [7346688.488728] beta systemd[1]: Listening on Network Management Resolve Hook Socket.117beta # [7346688.489870] beta systemd[1]: Starting Network Management...118alpha # [7346688.432590] alpha systemd[1]: Finished Update is Completed.119alpha # [7346688.503342] alpha systemd[1]: Finished Firewall.120alpha # [7346688.503461] alpha systemd[1]: Reached target Preparation for Network.121alpha # [7346688.503719] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.122alpha # [7346688.504878] alpha systemd[1]: Starting Network Management...123gamma # [7346688.491675] gamma systemd[1]: Finished Firewall.124gamma # [7346688.491827] gamma systemd[1]: Reached target Preparation for Network.125gamma # [7346688.492068] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.126gamma # [7346688.493090] gamma systemd[1]: Starting Network Management...127beta # [7346688.847773] beta systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted128beta # [7346688.847862] beta systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted129beta # [7346688.854653] beta systemd-networkd[204]: /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.130beta # [7346688.854818] beta systemd-networkd[204]: /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.131beta # [7346688.854976] beta systemd-networkd[204]: lo: Link UP132beta # [7346688.854981] beta systemd-networkd[204]: lo: Gained carrier133beta # [7346688.855152] beta systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.134beta # [7346688.855530] beta systemd[1]: Started Network Management.135beta # [7346688.855609] beta systemd-networkd[204]: eth1: Link UP136beta # [7346688.855848] beta systemd-networkd[204]: eth1: Gained carrier137beta # [7346688.856526] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...138beta # [7346688.954204] beta systemd-resolved[111]: Positive Trust Anchors:139beta # [7346688.954215] beta systemd-resolved[111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d140beta # [7346688.954218] beta systemd-resolved[111]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16141beta # [7346688.954254] beta systemd-resolved[111]: 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 test142beta # [7346688.968353] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.143beta # [7346688.976937] beta systemd-resolved[111]: Using system hostname 'beta'.144beta # [7346688.978434] beta systemd[1]: Started Network Name Resolution.145beta # [7346688.978524] beta systemd[1]: Reached target Network.146beta # [7346688.978610] beta systemd[1]: Reached target System Initialization.147beta # [7346688.978666] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container148beta # [7346688.978696] beta systemd[1]: Started Daily Cleanup of Temporary Directories.149beta # [7346688.978719] beta systemd[1]: Reached target Timer Units.150beta # [7346688.978863] beta systemd[1]: Listening on D-Bus System Message Bus Socket.151beta # [7346688.979001] beta systemd[1]: Listening on Nix Daemon Socket.152beta # [7346688.979138] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.153beta # [7346688.979167] beta systemd[1]: Reached target Socket Units.154beta # [7346688.979219] beta systemd[1]: Reached target Basic System.155beta # [7346688.980723] beta systemd[1]: Starting data mesher daemon...156beta # [7346688.981646] beta systemd[1]: Starting Import lastlog data into lastlog2 database...157beta # [7346688.982610] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...158beta # [7346688.984073] beta systemd[1]: Starting D-Bus System Message Bus...159beta # [7346689.002503] beta systemd[1]: Finished Import lastlog data into lastlog2 database.160beta # [7346689.098498] beta nsncd[211]: Sep 02 00:08:35.151 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"161beta # [7346689.098408] beta systemd[1]: Started Name Service Cache Daemon (nsncd).162beta # [7346689.098504] beta systemd[1]: Reached target Host and Network Name Lookups.163beta # [7346689.098614] beta systemd[1]: Reached target User and Group Name Lookups.164gamma # [7346688.851860] gamma systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted165gamma # [7346688.851951] gamma systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted166gamma # [7346688.858395] gamma systemd-networkd[204]: /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.167gamma # [7346688.858558] gamma systemd-networkd[204]: /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.168gamma # [7346688.858709] gamma systemd-networkd[204]: lo: Link UP169gamma # [7346688.858713] gamma systemd-networkd[204]: lo: Gained carrier170gamma # [7346688.858898] gamma systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.171gamma # [7346688.859261] gamma systemd[1]: Started Network Management.172gamma # [7346688.859336] gamma systemd-networkd[204]: eth1: Link UP173gamma # [7346688.859553] gamma systemd-networkd[204]: eth1: Gained carrier174gamma # [7346688.860186] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...175gamma # [7346688.968275] gamma systemd-resolved[108]: Positive Trust Anchors:176gamma # [7346688.968287] gamma systemd-resolved[108]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d177gamma # [7346688.968290] gamma systemd-resolved[108]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16178gamma # [7346688.968326] gamma systemd-resolved[108]: 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 test179gamma # [7346688.969524] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.180gamma # [7346688.990414] gamma systemd-resolved[108]: Using system hostname 'gamma'.181gamma # [7346688.991867] gamma systemd[1]: Started Network Name Resolution.182gamma # [7346688.991968] gamma systemd[1]: Reached target Network.183gamma # [7346688.992074] gamma systemd[1]: Reached target System Initialization.184gamma # [7346688.992143] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container185gamma # [7346688.992185] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.186gamma # [7346688.992211] gamma systemd[1]: Reached target Timer Units.187gamma # [7346688.992392] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.188gamma # [7346688.992547] gamma systemd[1]: Listening on Nix Daemon Socket.189gamma # [7346688.992705] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.190gamma # [7346688.992740] gamma systemd[1]: Reached target Socket Units.191gamma # [7346688.992803] gamma systemd[1]: Reached target Basic System.192gamma # [7346688.994499] gamma systemd[1]: Starting data mesher daemon...193gamma # [7346688.995559] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...194gamma # [7346688.996630] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...195gamma # [7346688.998186] gamma systemd[1]: Starting D-Bus System Message Bus...196gamma # [7346689.017238] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.197gamma # [7346689.121567] gamma nsncd[211]: Sep 02 00:08:35.174 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"198gamma # [7346689.121568] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).199gamma # [7346689.121634] gamma systemd[1]: Reached target Host and Network Name Lookups.200gamma # [7346689.121702] gamma systemd[1]: Reached target User and Group Name Lookups.201alpha # [7346688.861768] alpha systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted202alpha # [7346688.861851] alpha systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted203alpha # [7346688.868299] alpha systemd-networkd[204]: /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.204alpha # [7346688.868462] alpha systemd-networkd[204]: /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.205alpha # [7346688.868614] alpha systemd-networkd[204]: lo: Link UP206alpha # [7346688.868617] alpha systemd-networkd[204]: lo: Gained carrier207alpha # [7346688.868781] alpha systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.208alpha # [7346688.869164] alpha systemd[1]: Started Network Management.209alpha # [7346688.960277] alpha systemd-networkd[204]: eth1: Link UP210alpha # [7346688.960555] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...211alpha # [7346688.960599] alpha systemd-networkd[204]: eth1: Gained carrier212alpha # [7346688.989909] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.213alpha # [7346688.990410] alpha systemd-resolved[108]: Positive Trust Anchors:214alpha # [7346688.990421] alpha systemd-resolved[108]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d215alpha # [7346688.990424] alpha systemd-resolved[108]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16216alpha # [7346688.990460] alpha systemd-resolved[108]: 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 test217alpha # [7346689.012975] alpha systemd-resolved[108]: Using system hostname 'alpha'.218alpha # [7346689.014384] alpha systemd[1]: Started Network Name Resolution.219alpha # [7346689.014468] alpha systemd[1]: Reached target Network.220alpha # [7346689.014548] alpha systemd[1]: Reached target System Initialization.221alpha # [7346689.014604] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container222alpha # [7346689.014632] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.223alpha # [7346689.014651] alpha systemd[1]: Reached target Timer Units.224alpha # [7346689.014783] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.225alpha # [7346689.015012] alpha systemd[1]: Listening on Nix Daemon Socket.226alpha # [7346689.015155] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.227alpha # [7346689.015183] alpha systemd[1]: Reached target Socket Units.228alpha # [7346689.015236] alpha systemd[1]: Reached target Basic System.229alpha # [7346689.016666] alpha systemd[1]: Starting data mesher daemon...230alpha # [7346689.017227] alpha systemd[1]: lastlog2-import.service: Failed to spawn executor: No such file or directory231alpha # [7346689.017254] alpha systemd[1]: lastlog2-import.service: Failed to spawn 'start' task: No such file or directory232alpha # [7346689.017289] alpha systemd[1]: lastlog2-import.service: Failed with result 'resources'.233alpha # [7346689.017341] alpha systemd[1]: Failed to start Import lastlog data into lastlog2 database.234alpha # [7346689.018211] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...235alpha # [7346689.019496] alpha systemd[1]: Starting D-Bus System Message Bus...236alpha # [7346689.131931] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).237alpha # [7346689.132192] alpha nsncd[210]: Sep 02 00:08:35.185 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"238alpha # [7346689.131992] alpha systemd[1]: Reached target Host and Network Name Lookups.239alpha # [7346689.132076] alpha systemd[1]: Reached target User and Group Name Lookups.240beta # [7346689.100597] beta systemd[1]: Starting User Login Management...241beta # [7346689.101946] beta systemd[1]: Starting Permit User Sessions...242beta # [7346689.151344] beta systemd[1]: Finished Permit User Sessions.243beta # [7346689.152551] beta systemd[1]: Started Console Getty.244beta # [7346689.152602] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0245beta # [7346689.152629] beta systemd[1]: Reached target Login Prompts.246beta # [7346689.183286] beta dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...247beta # [7346689.184624] beta dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'248beta # [7346689.184624] beta dbus-broker-launch[212]: Invalid user-name in /nix/store/cpw251a8nnv83rza5mdsgkafc9msqh5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"249beta # [7346689.185301] beta systemd[1]: Started D-Bus System Message Bus.250beta # [7346689.194010] beta dbus-broker-launch[212]: Ready251gamma # [7346689.144464] gamma systemd[1]: Starting User Login Management...252gamma # [7346689.145839] gamma systemd[1]: Starting Permit User Sessions...253gamma # [7346689.158490] gamma systemd[1]: Finished Permit User Sessions.254gamma # [7346689.159733] gamma systemd[1]: Started Console Getty.255gamma # [7346689.159784] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0256gamma # [7346689.159808] gamma systemd[1]: Reached target Login Prompts.257gamma # [7346689.209218] gamma dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...258gamma # [7346689.210470] gamma dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'259gamma # [7346689.210470] gamma dbus-broker-launch[212]: Invalid user-name in /nix/store/cpw251a8nnv83rza5mdsgkafc9msqh5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"260gamma # [7346689.211065] gamma systemd[1]: Started D-Bus System Message Bus.261gamma # [7346689.219597] gamma dbus-broker-launch[212]: Ready262alpha # [7346689.144585] alpha systemd[1]: Starting User Login Management...263alpha # [7346689.146127] alpha systemd[1]: Starting Permit User Sessions...264alpha # [7346689.156693] alpha systemd[1]: Finished Permit User Sessions.265alpha # [7346689.157928] alpha systemd[1]: Started Console Getty.266alpha # [7346689.157981] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0267alpha # [7346689.158004] alpha systemd[1]: Reached target Login Prompts.268alpha # [7346689.222550] alpha dbus-broker-launch[211]: Looking up NSS user entry for 'systemd-timesync'...269alpha # [7346689.223700] alpha dbus-broker-launch[211]: NSS returned no entry for 'systemd-timesync'270alpha # [7346689.223700] alpha dbus-broker-launch[211]: Invalid user-name in /nix/store/cpw251a8nnv83rza5mdsgkafc9msqh5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"271alpha # [7346689.224079] alpha systemd[1]: Started D-Bus System Message Bus.272alpha # [7346689.232071] alpha dbus-broker-launch[211]: Ready273beta # [7346689.437361] beta data-mesher[209]: time=2026-09-02T00:08:35.490Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]274beta # [7346689.438371] beta data-mesher[209]: time=2026-09-02T00:08:35.491Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4: [/dns/alpha.clan/tcp/7946]} {12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE275beta # [7346689.438371] beta data-mesher[209]: time=2026-09-02T00:08:35.491Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml276beta # [7346689.478465] beta data-mesher[209]: time=2026-09-02T00:08:35.531Z level=INFO msg="checking file integrity"277beta # [7346689.478567] beta data-mesher[209]: time=2026-09-02T00:08:35.531Z level=INFO msg="file integrity check complete"278beta # [7346689.482741] beta data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="libp2p host created" peer_id=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE 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]"279beta # [7346689.482806] beta data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="registered HTTP route" method=GET path=/files280beta # [7346689.482806] beta data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name281beta # [7346689.482806] beta data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name282beta # [7346689.482806] beta data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="starting server"283beta # [7346689.482970] beta data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="waiting for DHT to populate" delay=10s284beta # [7346689.482970] beta data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="HTTP server listening" address=[::1]:7331285beta # [7346689.483061] beta data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331286beta # [7346689.489392] beta data-mesher[209]: time=2026-09-02T00:08:35.542Z level=INFO msg="peer connected" peer_id=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 remote_addr=/ip4/192.168.1.1/tcp/7946287beta # [7346689.490424] beta data-mesher[209]: time=2026-09-02T00:08:35.543Z level=INFO msg="peer connected" peer_id=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 remote_addr=/ip4/192.168.1.1/tcp/7946288beta # [7346689.492886] beta data-mesher[209]: time=2026-09-02T00:08:35.546Z level=INFO msg="peer connected" peer_id=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM remote_addr=/ip4/192.168.1.3/tcp/7946289beta # [7346689.566083] beta systemd-logind[228]: New seat seat0.290beta # [7346689.566266] beta systemd[1]: Started User Login Management.291beta # [7346689.567476] beta systemd[1]: Starting linger-users.service...292alpha # [7346689.454060] alpha data-mesher[209]: time=2026-09-02T00:08:35.507Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]293alpha # [7346689.455055] alpha data-mesher[209]: time=2026-09-02T00:08:35.508Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4: [/dns/alpha.clan/tcp/7946]} {12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4294alpha # [7346689.455055] alpha data-mesher[209]: time=2026-09-02T00:08:35.508Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml295alpha # [7346689.478454] alpha data-mesher[209]: time=2026-09-02T00:08:35.531Z level=INFO msg="checking file integrity"296alpha # [7346689.478579] alpha data-mesher[209]: time=2026-09-02T00:08:35.531Z level=INFO msg="file integrity check complete"297alpha # [7346689.482915] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="libp2p host created" peer_id=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 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]"298alpha # [7346689.482990] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="registered HTTP route" method=GET path=/files299alpha # [7346689.482990] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name300alpha # [7346689.482990] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name301alpha # [7346689.482990] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="starting server"302alpha # [7346689.483165] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="waiting for DHT to populate" delay=10s303alpha # [7346689.483165] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="HTTP server listening" address=[::1]:7331304alpha # [7346689.483165] alpha data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331305alpha # [7346689.489630] alpha data-mesher[209]: time=2026-09-02T00:08:35.542Z level=INFO msg="peer connected" peer_id=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE remote_addr=/ip4/192.168.1.2/tcp/7946306alpha # [7346689.490363] alpha data-mesher[209]: time=2026-09-02T00:08:35.543Z level=INFO msg="peer connected" peer_id=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE remote_addr=/ip4/192.168.1.2/tcp/50110307alpha # [7346689.497720] alpha data-mesher[209]: time=2026-09-02T00:08:35.550Z level=INFO msg="peer connected" peer_id=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM remote_addr=/ip4/192.168.1.3/tcp/7946308alpha # [7346689.588452] alpha systemd-logind[224]: New seat seat0.309alpha # [7346689.588630] alpha systemd[1]: Started User Login Management.310gamma # [7346689.444309] gamma data-mesher[209]: time=2026-09-02T00:08:35.497Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]311gamma # [7346689.445434] gamma data-mesher[209]: time=2026-09-02T00:08:35.498Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4: [/dns/alpha.clan/tcp/7946]} {12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM312gamma # [7346689.445434] gamma data-mesher[209]: time=2026-09-02T00:08:35.498Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml313gamma # [7346689.478458] gamma data-mesher[209]: time=2026-09-02T00:08:35.531Z level=INFO msg="checking file integrity"314gamma # [7346689.478576] gamma data-mesher[209]: time=2026-09-02T00:08:35.531Z level=INFO msg="file integrity check complete"315gamma # [7346689.482694] gamma data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="libp2p host created" peer_id=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM 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]"316gamma # [7346689.482792] gamma data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="registered HTTP route" method=GET path=/files317gamma # [7346689.482792] gamma data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name318gamma # [7346689.482792] gamma data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name319gamma # [7346689.482792] gamma data-mesher[209]: time=2026-09-02T00:08:35.535Z level=INFO msg="starting server"320gamma # [7346689.482974] gamma data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="waiting for DHT to populate" delay=10s321gamma # [7346689.483043] gamma data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="HTTP server listening" address=[::1]:7331322gamma # [7346689.483200] gamma data-mesher[209]: time=2026-09-02T00:08:35.536Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331323gamma # [7346689.491911] gamma data-mesher[209]: time=2026-09-02T00:08:35.545Z level=INFO msg="peer connected" peer_id=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE remote_addr=/ip4/192.168.1.2/tcp/7946324gamma # [7346689.497047] gamma data-mesher[209]: time=2026-09-02T00:08:35.550Z level=INFO msg="peer connected" peer_id=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 remote_addr=/ip4/192.168.1.1/tcp/7946325gamma # [7346689.580312] gamma systemd-logind[228]: New seat seat0.326gamma # [7346689.580540] gamma systemd[1]: Started User Login Management.327gamma # [7346689.788905] gamma systemd[1]: Starting linger-users.service...328gamma # [7346689.801940] gamma systemd[1]: linger-users.service: Deactivated successfully.329gamma # [7346689.802035] gamma systemd[1]: Finished linger-users.service.330alpha # [7346689.788741] alpha systemd[1]: Starting linger-users.service...331alpha # [7346689.801582] alpha systemd[1]: linger-users.service: Deactivated successfully.332alpha # [7346689.801650] alpha systemd[1]: Finished linger-users.service.333beta # [7346689.797285] beta systemd[1]: linger-users.service: Deactivated successfully.334beta # [7346689.797436] beta systemd[1]: Finished linger-users.service.335beta # [7346689.956227] beta systemd-networkd[204]: eth1: Gained IPv6LL336alpha # [7346690.144317] alpha systemd-networkd[204]: eth1: Gained IPv6LL337gamma # [7346690.176288] gamma systemd-networkd[204]: eth1: Gained IPv6LL338beta # [7346691.671778] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.339beta # [7346691.673826] beta systemd[1]: Finished Save Transient machine-id to Disk.340alpha # [7346691.671616] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.341alpha # [7346691.673824] alpha systemd[1]: Finished Save Transient machine-id to Disk.342gamma # [7346691.672111] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.343gamma # [7346691.673983] gamma systemd[1]: Finished Save Transient machine-id to Disk.344alpha: still waiting for container 'alpha' to reach ready state...345alpha: (finished: waiting for unit data-mesher.service, in 12.16 seconds)346beta: waiting for unit data-mesher.service347beta: (finished: waiting for unit data-mesher.service, in 0.01 seconds)348gamma: waiting for unit data-mesher.service349gamma: (finished: waiting for unit data-mesher.service, in 0.01 seconds)350alpha: must succeed: echo -n 'hello world' > /tmp/test_file351alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.00 seconds)352alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key353alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)354??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.355 File "/nix/store/pg5lbaicpvf34wrdmfh8riwp8avk7lk6-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39356alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file357??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.358 File "/nix/store/pg5lbaicpvf34wrdmfh8riwp8avk7lk6-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39359alpha: (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)360beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file361alpha # [7346699.483347] alpha data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="performing state exchange with peers on join" count=1362alpha # [7346699.483347] alpha data-mesher[209]: time=2026-09-02T00:08:45.536Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s363alpha # [7346699.483724] alpha data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="received state sync from peer" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM364alpha # [7346699.483724] alpha data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM365alpha # [7346699.483853] alpha data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM366alpha # [7346699.483853] alpha data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="state exchange complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s367alpha # [7346699.483900] alpha data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="server started"368alpha # [7346699.484109] alpha systemd[1]: Started data mesher daemon.369alpha # [7346699.484401] alpha data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="starting expired-file sweeper" interval=1m0s370alpha # [7346699.484375] alpha systemd[1]: Reached target Multi-User System.371alpha # [7346699.484556] alpha systemd[1]: Startup finished in 11.575s.372alpha # [7346699.643957] alpha data-mesher[209]: time=2026-09-02T00:08:45.697Z level=INFO msg=http_request uri=/files/test_file status=204373beta # [7346699.483347] beta data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="performing state exchange with peers on join" count=1374beta # [7346699.483347] beta data-mesher[209]: time=2026-09-02T00:08:45.536Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s375beta # [7346699.484054] beta data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM376beta # [7346699.484054] beta data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="state exchange complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s377gamma # [7346699.483315] gamma data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="performing state exchange with peers on join" count=1378beta # [7346699.484138] beta data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="server started"379gamma # [7346699.483691] gamma data-mesher[209]: time=2026-09-02T00:08:45.536Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s380beta # [7346699.484295] beta data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="starting expired-file sweeper" interval=1m0s381gamma # [7346699.483721] gamma data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="received state sync from peer" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4382beta # [7346699.484287] beta systemd[1]: Started data mesher daemon.383gamma # [7346699.483721] gamma data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4384beta # [7346699.484525] beta systemd[1]: Reached target Multi-User System.385gamma # [7346699.483791] gamma data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="received state sync from peer" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE386beta # [7346699.484738] beta systemd[1]: Startup finished in 11.589s.387gamma # [7346699.483791] gamma data-mesher[209]: time=2026-09-02T00:08:45.536Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE388gamma # [7346699.483844] gamma data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4389gamma # [7346699.483844] gamma data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="state exchange complete" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s390gamma # [7346699.483881] gamma data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="server started"391gamma # [7346699.483930] gamma data-mesher[209]: time=2026-09-02T00:08:45.537Z level=INFO msg="starting expired-file sweeper" interval=1m0s392gamma # [7346699.484109] gamma systemd[1]: Started data mesher daemon.393gamma # [7346699.484375] gamma systemd[1]: Reached target Multi-User System.394gamma # [7346699.484487] gamma systemd[1]: Startup finished in 11.592s.395beta: (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)396gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file397gamma: (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)398beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key399beta # [7346704.484746] beta data-mesher[209]: time=2026-09-02T00:08:50.537Z level=DEBUG msg="attempting push/pull" peer_count=2400alpha # [7346704.484334] alpha data-mesher[209]: time=2026-09-02T00:08:50.537Z level=DEBUG msg="attempting push/pull" peer_count=2401beta # [7346704.485104] beta data-mesher[209]: time=2026-09-02T00:08:50.537Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s402alpha # [7346704.484767] alpha data-mesher[209]: time=2026-09-02T00:08:50.537Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s403alpha # [7346704.486421] alpha data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="received state sync from peer" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM404alpha # [7346704.486421] alpha data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM405alpha # [7346704.486489] alpha data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE406alpha # [7346704.486489] alpha data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="state exchange complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s407alpha # [7346704.486557] alpha data-mesher[209]: time=2026-09-02T00:08:50.539Z level=DEBUG msg="push/pull successful" interval=5s408alpha # [7346704.486629] alpha data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="received file request" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_file409alpha # [7346704.487312] alpha data-mesher[209]: time=2026-09-02T00:08:50.540Z level=INFO msg="file transfer complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_file410alpha # [7346704.487436] alpha data-mesher[209]: time=2026-09-02T00:08:50.540Z level=INFO msg="received file request" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_file411alpha # [7346704.489596] alpha data-mesher[209]: time=2026-09-02T00:08:50.542Z level=INFO msg="file transfer complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_file412beta # [7346704.485511] beta data-mesher[209]: time=2026-09-02T00:08:50.538Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM413beta # [7346704.485511] beta data-mesher[209]: time=2026-09-02T00:08:50.538Z level=INFO msg="state exchange complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s414beta # [7346704.485571] beta data-mesher[209]: time=2026-09-02T00:08:50.538Z level=DEBUG msg="push/pull successful" interval=5s415beta # [7346704.485734] beta data-mesher[209]: time=2026-09-02T00:08:50.538Z level=INFO msg="received state sync from peer" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4416beta # [7346704.485761] beta data-mesher[209]: time=2026-09-02T00:08:50.538Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4417beta # [7346704.485803] beta data-mesher[209]: time=2026-09-02T00:08:50.538Z level=DEBUG msg="new file detected" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 name=test_file name=test_file418beta # [7346704.485974] beta data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="scheduling file download" name=test_file419beta # [7346704.486047] beta data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-02 00:08:45.689 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4420gamma # [7346704.485095] gamma data-mesher[209]: time=2026-09-02T00:08:50.538Z level=DEBUG msg="attempting push/pull" peer_count=2421gamma # [7346704.485741] gamma data-mesher[209]: time=2026-09-02T00:08:50.538Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s422gamma # [7346704.485741] gamma data-mesher[209]: time=2026-09-02T00:08:50.538Z level=INFO msg="received state sync from peer" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE423gamma # [7346704.485741] gamma data-mesher[209]: time=2026-09-02T00:08:50.538Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE424gamma # [7346704.486695] gamma data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4425gamma # [7346704.486695] gamma data-mesher[209]: time=2026-09-02T00:08:50.539Z level=DEBUG msg="new file detected" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 name=test_file name=test_file426gamma # [7346704.486815] gamma data-mesher[209]: time=2026-09-02T00:08:50.539Z level=INFO msg="state exchange complete" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s427gamma # [7346704.486815] gamma data-mesher[209]: time=2026-09-02T00:08:50.539Z level=DEBUG msg="push/pull successful" interval=5s428gamma # [7346704.486985] gamma data-mesher[209]: time=2026-09-02T00:08:50.540Z level=INFO msg="scheduling file download" name=test_file429gamma # [7346704.487049] gamma data-mesher[209]: time=2026-09-02T00:08:50.540Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-02 00:08:45.689 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4430beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.13 seconds)431alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file432gamma # [7346704.820171] gamma data-mesher[209]: time=2026-09-02T00:08:50.873Z level=INFO msg="download complete" name=test_file signed_at="2026-09-02 00:08:45.689 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 written=true elapsed=333.100802ms433beta # [7346704.788703] beta data-mesher[209]: time=2026-09-02T00:08:50.841Z level=INFO msg="download complete" name=test_file signed_at="2026-09-02 00:08:45.689 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 written=true elapsed=302.679749ms434beta # [7346704.824223] beta data-mesher[209]: time=2026-09-02T00:08:50.877Z level=INFO msg=http_request uri=/files/test_file status=204435gamma # [7346709.487485] gamma data-mesher[209]: time=2026-09-02T00:08:55.540Z level=DEBUG msg="attempting push/pull" peer_count=2436gamma # [7346709.487485] gamma data-mesher[209]: time=2026-09-02T00:08:55.540Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s437gamma # [7346709.488226] gamma data-mesher[209]: time=2026-09-02T00:08:55.540Z level=INFO msg="received state sync from peer" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4438gamma # [7346709.488226] gamma data-mesher[209]: time=2026-09-02T00:08:55.540Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4439gamma # [7346709.488786] gamma data-mesher[209]: time=2026-09-02T00:08:55.541Z level=INFO msg="received state sync from peer" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE440gamma # [7346709.488786] gamma data-mesher[209]: time=2026-09-02T00:08:55.541Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE441gamma # [7346709.490066] gamma data-mesher[209]: time=2026-09-02T00:08:55.543Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE442gamma # [7346709.597192] gamma data-mesher[209]: time=2026-09-02T00:08:55.650Z level=DEBUG msg="imported tombstone" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE name=test_file written=true443gamma # [7346709.597344] gamma data-mesher[209]: time=2026-09-02T00:08:55.650Z level=DEBUG msg="imported tombstone" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE name=test_file written=false444gamma # [7346709.597344] gamma data-mesher[209]: time=2026-09-02T00:08:55.650Z level=INFO msg="state exchange complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s445gamma # [7346709.597344] gamma data-mesher[209]: time=2026-09-02T00:08:55.650Z level=DEBUG msg="push/pull successful" interval=5s446beta # [7346709.486276] beta data-mesher[209]: time=2026-09-02T00:08:55.539Z level=DEBUG msg="attempting push/pull" peer_count=2447beta # [7346709.486276] beta data-mesher[209]: time=2026-09-02T00:08:55.539Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s448beta # [7346709.489145] beta data-mesher[209]: time=2026-09-02T00:08:55.542Z level=INFO msg="received state sync from peer" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM449beta # [7346709.489145] beta data-mesher[209]: time=2026-09-02T00:08:55.542Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM450beta # [7346709.597546] beta data-mesher[209]: time=2026-09-02T00:08:55.650Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM451beta # [7346709.597628] beta data-mesher[209]: time=2026-09-02T00:08:55.650Z level=INFO msg="state exchange complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s452beta # [7346709.597628] beta data-mesher[209]: time=2026-09-02T00:08:55.650Z level=DEBUG msg="push/pull successful" interval=5s453alpha # [7346709.487163] alpha data-mesher[209]: time=2026-09-02T00:08:55.539Z level=DEBUG msg="attempting push/pull" peer_count=2454alpha # [7346709.487163] alpha data-mesher[209]: time=2026-09-02T00:08:55.540Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s455alpha # [7346709.488941] alpha data-mesher[209]: time=2026-09-02T00:08:55.542Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM456alpha # [7346709.489034] alpha data-mesher[209]: time=2026-09-02T00:08:55.542Z level=INFO msg="state exchange complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s457alpha # [7346709.489034] alpha data-mesher[209]: time=2026-09-02T00:08:55.542Z level=DEBUG msg="push/pull successful" interval=5s458alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.08 seconds)459beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file460beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)461gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file462gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)463alpha: must succeed: cat /nix/store/l5131wfmxh8bh6mjx48cn4xlfmw4xd2a-per-machine-alpha-data-mesher-node-identity_identity.pub464alpha: (finished: must succeed: cat /nix/store/l5131wfmxh8bh6mjx48cn4xlfmw4xd2a-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)465alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file466alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)467alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert468alpha # [7346714.489561] alpha data-mesher[209]: time=2026-09-02T00:09:00.542Z level=DEBUG msg="attempting push/pull" peer_count=2469alpha # [7346714.489561] alpha data-mesher[209]: time=2026-09-02T00:09:00.542Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s470alpha # [7346714.490933] alpha data-mesher[209]: time=2026-09-02T00:09:00.544Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE471alpha # [7346714.500165] alpha data-mesher[209]: time=2026-09-02T00:09:00.553Z level=DEBUG msg="imported tombstone" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE name=test_file written=true472alpha # [7346714.500165] alpha data-mesher[209]: time=2026-09-02T00:09:00.553Z level=INFO msg="state exchange complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s473alpha # [7346714.500288] alpha data-mesher[209]: time=2026-09-02T00:09:00.553Z level=DEBUG msg="push/pull successful" interval=5s474alpha # [7346714.962567] alpha data-mesher[209]: time=2026-09-02T00:09:01.015Z level=INFO msg=http_request uri=/files/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM status=204475alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)476alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM477alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM, in 0.01 seconds)478beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM479beta # [7346714.490224] beta data-mesher[209]: time=2026-09-02T00:09:00.543Z level=INFO msg="received state sync from peer" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4480beta # [7346714.490224] beta data-mesher[209]: time=2026-09-02T00:09:00.543Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4481beta # [7346714.598619] beta data-mesher[209]: time=2026-09-02T00:09:00.651Z level=DEBUG msg="attempting push/pull" peer_count=2482beta # [7346714.598619] beta data-mesher[209]: time=2026-09-02T00:09:00.651Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s483beta # [7346714.598853] beta data-mesher[209]: time=2026-09-02T00:09:00.651Z level=INFO msg="received state sync from peer" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM484beta # [7346714.598853] beta data-mesher[209]: time=2026-09-02T00:09:00.651Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM485beta # [7346714.599630] beta data-mesher[209]: time=2026-09-02T00:09:00.652Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM486beta # [7346714.599630] beta data-mesher[209]: time=2026-09-02T00:09:00.652Z level=INFO msg="state exchange complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s487beta # [7346714.599892] beta data-mesher[209]: time=2026-09-02T00:09:00.652Z level=DEBUG msg="push/pull successful" interval=5s488gamma # [7346714.598289] gamma data-mesher[209]: time=2026-09-02T00:09:00.651Z level=DEBUG msg="attempting push/pull" peer_count=2489gamma # [7346714.598289] gamma data-mesher[209]: time=2026-09-02T00:09:00.651Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s490gamma # [7346714.599507] gamma data-mesher[209]: time=2026-09-02T00:09:00.652Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE491gamma # [7346714.599507] gamma data-mesher[209]: time=2026-09-02T00:09:00.652Z level=INFO msg="received state sync from peer" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE492gamma # [7346714.599507] gamma data-mesher[209]: time=2026-09-02T00:09:00.652Z level=INFO msg="state exchange complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s493gamma # [7346714.599507] gamma data-mesher[209]: time=2026-09-02T00:09:00.652Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE494gamma # [7346714.599507] gamma data-mesher[209]: time=2026-09-02T00:09:00.652Z level=DEBUG msg="push/pull successful" interval=5s495beta # [7346719.502756] beta data-mesher[209]: time=2026-09-02T00:09:05.554Z level=INFO msg="received state sync from peer" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4496beta # [7346719.502756] beta data-mesher[209]: time=2026-09-02T00:09:05.554Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4497beta # [7346719.502756] beta data-mesher[209]: time=2026-09-02T00:09:05.555Z level=DEBUG msg="new file detected" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 name=test_file name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM498beta # [7346719.502756] beta data-mesher[209]: time=2026-09-02T00:09:05.555Z level=INFO msg="scheduling file download" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM499beta # [7346719.502756] beta data-mesher[209]: time=2026-09-02T00:09:05.555Z level=INFO msg="downloading file" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM signed_at="2026-09-02 00:09:01.011 +0000 UTC" signed_by="f4/BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4500beta # [7346719.506842] beta data-mesher[209]: time=2026-09-02T00:09:05.559Z level=INFO msg="download complete" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM signed_at="2026-09-02 00:09:01.011 +0000 UTC" signed_by="f4/BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 written=true elapsed=4.505821ms501beta # [7346719.600698] beta data-mesher[209]: time=2026-09-02T00:09:05.653Z level=DEBUG msg="attempting push/pull" peer_count=2502beta # [7346719.600774] beta data-mesher[209]: time=2026-09-02T00:09:05.653Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s503beta # [7346719.601829] beta data-mesher[209]: time=2026-09-02T00:09:05.654Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4504beta # [7346719.602063] beta data-mesher[209]: time=2026-09-02T00:09:05.655Z level=INFO msg="state exchange complete" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s505beta # [7346719.602091] beta data-mesher[209]: time=2026-09-02T00:09:05.655Z level=DEBUG msg="push/pull successful" interval=5s506alpha # [7346719.500782] alpha data-mesher[209]: time=2026-09-02T00:09:05.553Z level=DEBUG msg="attempting push/pull" peer_count=2507alpha # [7346719.500782] alpha data-mesher[209]: time=2026-09-02T00:09:05.553Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s508alpha # [7346719.502835] alpha data-mesher[209]: time=2026-09-02T00:09:05.555Z level=INFO msg="received file request" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM509alpha # [7346719.502911] alpha data-mesher[209]: time=2026-09-02T00:09:05.556Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE510alpha # [7346719.502911] alpha data-mesher[209]: time=2026-09-02T00:09:05.556Z level=INFO msg="state exchange complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s511alpha # [7346719.502980] alpha data-mesher[209]: time=2026-09-02T00:09:05.556Z level=DEBUG msg="push/pull successful" interval=5s512alpha # [7346719.505256] alpha data-mesher[209]: time=2026-09-02T00:09:05.558Z level=INFO msg="file transfer complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM513alpha # [7346719.600480] alpha data-mesher[209]: time=2026-09-02T00:09:05.653Z level=INFO msg="received state sync from peer" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM514alpha # [7346719.600480] alpha data-mesher[209]: time=2026-09-02T00:09:05.653Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM515alpha # [7346719.601123] alpha data-mesher[209]: time=2026-09-02T00:09:05.654Z level=INFO msg="received state sync from peer" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE516alpha # [7346719.601123] alpha data-mesher[209]: time=2026-09-02T00:09:05.654Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE517alpha # [7346719.602169] alpha data-mesher[209]: time=2026-09-02T00:09:05.655Z level=INFO msg="received file request" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM518alpha # [7346719.602698] alpha data-mesher[209]: time=2026-09-02T00:09:05.655Z level=INFO msg="file transfer complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM network="p+EbQlfx1rUgJomJRUDzJAf6PIpm6nS/GohhSA/UFj0=" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM519beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM, in 5.05 seconds)520gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM521gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM, in 0.01 seconds)522alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert523alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)524alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub525Error: failed to update file: 403 Forbidden, signer f4/BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM= is not authorized for this file test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM526alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/1pmyrkfsrk840rfs5w23bzbnxc277n8v-shared-data-mesher-network_network.pub, in 0.02 seconds)527(finished: run the VM test script, in 32.64 seconds)528gamma # [7346719.599931] gamma data-mesher[209]: time=2026-09-02T00:09:05.653Z level=DEBUG msg="attempting push/pull" peer_count=2529gamma # [7346719.599931] gamma data-mesher[209]: time=2026-09-02T00:09:05.653Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s530gamma # [7346719.600942] gamma data-mesher[209]: time=2026-09-02T00:09:05.654Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4531gamma # [7346719.601538] gamma data-mesher[209]: time=2026-09-02T00:09:05.654Z level=DEBUG msg="new file detected" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 name=test_file name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM532gamma # [7346719.601574] gamma data-mesher[209]: time=2026-09-02T00:09:05.654Z level=INFO msg="state exchange complete" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s533gamma # [7346719.601614] gamma data-mesher[209]: time=2026-09-02T00:09:05.654Z level=DEBUG msg="push/pull successful" interval=5s534gamma # [7346719.601724] gamma data-mesher[209]: time=2026-09-02T00:09:05.654Z level=INFO msg="scheduling file download" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM535gamma # [7346719.601792] gamma data-mesher[209]: time=2026-09-02T00:09:05.654Z level=INFO msg="downloading file" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM signed_at="2026-09-02 00:09:01.011 +0000 UTC" signed_by="f4/BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4536gamma # [7346719.605049] gamma data-mesher[209]: time=2026-09-02T00:09:05.658Z level=INFO msg="download complete" name=test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM signed_at="2026-09-02 00:09:01.011 +0000 UTC" signed_by="f4/BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM=" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 written=true elapsed=3.242564ms537alpha # [7346720.057248] alpha data-mesher[209]: time=2026-09-02T00:09:06.110Z level=INFO msg=http_request uri=/files/test_ns/f4_BMzZ4ld81B4lUxpWLhNDC9pswVNLTYnm5dL9afqM status=403538beta # [7346724.504144] beta data-mesher[209]: time=2026-09-02T00:09:10.557Z level=INFO msg="received state sync from peer" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4539beta # [7346724.504144] beta data-mesher[209]: time=2026-09-02T00:09:10.557Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4540beta # [7346724.602527] beta data-mesher[209]: time=2026-09-02T00:09:10.655Z level=DEBUG msg="attempting push/pull" peer_count=2541beta # [7346724.602527] beta data-mesher[209]: time=2026-09-02T00:09:10.655Z level=DEBUG msg="initiating state exchange" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s542beta # [7346724.604450] beta data-mesher[209]: time=2026-09-02T00:09:10.657Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM543beta # [7346724.605041] beta data-mesher[209]: time=2026-09-02T00:09:10.658Z level=INFO msg="state exchange complete" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM timeout=5s544beta # [7346724.608553] beta data-mesher[209]: time=2026-09-02T00:09:10.658Z level=DEBUG msg="push/pull successful" interval=5s545alpha # [7346724.503363] alpha data-mesher[209]: time=2026-09-02T00:09:10.556Z level=DEBUG msg="attempting push/pull" peer_count=2546alpha # [7346724.504131] alpha data-mesher[209]: time=2026-09-02T00:09:10.556Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s547alpha # [7346724.505161] alpha data-mesher[209]: time=2026-09-02T00:09:10.558Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE548alpha # [7346724.505772] alpha data-mesher[209]: time=2026-09-02T00:09:10.558Z level=INFO msg="state exchange complete" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE timeout=5s549alpha # [7346724.505854] alpha data-mesher[209]: time=2026-09-02T00:09:10.558Z level=DEBUG msg="push/pull successful" interval=5s550alpha # [7346724.604132] alpha data-mesher[209]: time=2026-09-02T00:09:10.656Z level=INFO msg="received state sync from peer" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM551alpha # [7346724.604132] alpha data-mesher[209]: time=2026-09-02T00:09:10.656Z level=INFO msg="merging remote state" peer=12D3KooWPj1ge5YQYheKr6gktiYJnG7E29AkZvPjiZioxoB9fZFM552gamma # [7346724.602787] gamma data-mesher[209]: time=2026-09-02T00:09:10.655Z level=DEBUG msg="attempting push/pull" peer_count=2553gamma # [7346724.602787] gamma data-mesher[209]: time=2026-09-02T00:09:10.655Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s554gamma # [7346724.603572] gamma data-mesher[209]: time=2026-09-02T00:09:10.656Z level=INFO msg="received state sync from peer" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE555gamma # [7346724.603572] gamma data-mesher[209]: time=2026-09-02T00:09:10.656Z level=INFO msg="merging remote state" peer=12D3KooW9wYWN7EAJmgszP83uSWbHHdyx5BiDuY2ZUjxJXLrSqsE556gamma # [7346724.604579] gamma data-mesher[209]: time=2026-09-02T00:09:10.657Z level=INFO msg="merging remote state" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4557gamma # [7346724.605172] gamma data-mesher[209]: time=2026-09-02T00:09:10.658Z level=INFO msg="state exchange complete" peer=12D3KooWJQK5pA9ukzPxiHu6mkpLJv7dyPundxGXu4RMwbRqbEP4 timeout=5s558gamma # [7346724.608558] gamma data-mesher[209]: time=2026-09-02T00:09:10.658Z level=DEBUG msg="push/pull successful" interval=5s559test script finished in 38.18s560cleanup561kill NspawnMachine (pid 53)562kill NspawnMachine (pid 55)563kill NspawnMachine (pid 54)564Container alpha terminated by signal KILL.565Container beta terminated by signal KILL.566gamma # [7346725.831034] gamma systemd-networkd[204]: eth1: Link DOWN567gamma # [7346725.831049] gamma systemd-networkd[204]: eth1: Lost carrier568gamma # [7346725.864539] gamma systemd-networkd[204]: eth1: Lost IPv6LL address fe80::30c3:34ff:fef9:525c.569Container gamma terminated by signal KILL.570(finished: cleanup, in 0.64 seconds)

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

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