container-test-run-data-mesher
checks.aarch64-linux.data-mesher
· build #500
· 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 55)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(gamma): TAP vde-tap1 not found; container will be isolated from VDE21nixos-nspawn(gamma): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.22nixos-nspawn(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(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 gamma on /build/vm-state-gamma.31░ Spawning container beta on /build/vm-state-beta.32beta # No journal files were found.33gamma # No journal files were found.34beta # No journal boot entry found for the specified boot (+0).35gamma # No journal boot entry found for the specified boot (+0).36alpha # No journal files were found.37alpha # No journal boot entry found for the specified boot (+0).38beta # [6727659.246954] beta systemd-journald[87]: Journal started39beta # [6727659.247009] beta systemd-journald[87]: Runtime Journal (/run/log/journal/644b3c539bc94e7db3655e8b4b608b74) is 8M, max 2.5G, 2.4G free.40beta # [6727659.265640] beta systemd[1]: Starting Flush Journal to Persistent Storage...41beta # [6727659.266498] beta systemd[1]: Starting Network Name Resolution...42beta # [6727659.267218] beta systemd[1]: Starting Create Static Device Nodes in /dev...43beta # [6727659.275384] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/644b3c539bc94e7db3655e8b4b608b74 is 1.621ms for 5 entries.44beta # [6727659.275384] beta systemd-journald[87]: System Journal (/var/log/journal/644b3c539bc94e7db3655e8b4b608b74) is 8M, max 4G, 3.9G free.45beta # [6727659.292212] beta systemd[1]: Finished Create Static Device Nodes in /dev.46beta # [6727659.292538] beta systemd[1]: Reached target Preparation for Local File Systems.47beta # [6727659.292626] beta systemd[1]: Reached target Local File Systems.48beta # [6727659.293453] beta systemd[1]: Listening on Boot Loader Control Service Socket.49beta # [6727659.293500] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container50beta # [6727659.294472] beta systemd[1]: Starting Save Transient machine-id to Disk...51beta # [6727659.294509] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys52beta # [6727659.452300] beta systemd[1]: Finished Firewall.53beta # [6727659.452770] beta systemd[1]: Finished Flush Journal to Persistent Storage.54beta # [6727659.454003] beta systemd[1]: Reached target Preparation for Network.55beta # [6727659.454317] beta systemd[1]: Listening on Network Management Resolve Hook Socket.56beta # [6727659.455870] beta systemd[1]: Starting Network Management...57beta # [6727659.456719] beta systemd[1]: Starting Create System Files and Directories...58beta # [6727659.471747] beta systemd-tmpfiles[197]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted59beta # [6727659.471931] beta systemd-tmpfiles[197]: fchmod() of /var/log/journal failed: Operation not permitted60beta # [6727659.472294] beta systemd-tmpfiles[197]: fchmod() of /var/log/journal/644b3c539bc94e7db3655e8b4b608b74 failed: Operation not permitted61beta # [6727659.472494] beta systemd-tmpfiles[197]: fchmod() of /run/log/journal failed: Operation not permitted62beta # [6727659.474957] beta systemd[1]: Finished Create System Files and Directories.63beta # [6727659.477603] beta systemd[1]: Starting Rebuild Journal Catalog...64beta # [6727659.478642] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...65beta # [6727659.490993] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.66beta # [6727659.498900] beta systemd[1]: Finished Rebuild Journal Catalog.67beta # [6727659.500690] beta systemd[1]: Starting Update is Completed...68beta # [6727659.514447] beta systemd[1]: Finished Update is Completed.69beta # [6727660.097756] beta systemd-networkd[196]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted70beta # [6727660.097854] beta systemd-networkd[196]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted71beta # [6727660.104593] beta systemd-networkd[196]: /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.72beta # [6727660.104758] beta systemd-networkd[196]: /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.73beta # [6727660.104919] beta systemd-networkd[196]: lo: Link UP74beta # [6727660.104923] beta systemd-networkd[196]: lo: Gained carrier75beta # [6727660.105104] beta systemd-networkd[196]: eth1: Configuring with /etc/systemd/network/40-eth1.network.76beta # [6727660.105540] beta systemd[1]: Started Network Management.77beta # [6727660.105617] beta systemd-networkd[196]: eth1: Link UP78beta # [6727660.105833] beta systemd-networkd[196]: eth1: Gained carrier79beta # [6727660.106553] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...80alpha # [6727659.244490] alpha systemd-journald[87]: Journal started81alpha # [6727659.244555] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/dfdae8cac650459a8378d17a14de9106) is 8M, max 2.5G, 2.4G free.82alpha # [6727659.247549] alpha systemd[1]: Starting Flush Journal to Persistent Storage...83alpha # [6727659.257112] alpha systemd[1]: Finished Create Static Device Nodes in /dev.84alpha # [6727659.257388] alpha systemd[1]: Reached target Preparation for Local File Systems.85alpha # [6727659.257487] alpha systemd[1]: Reached target Local File Systems.86alpha # [6727659.258085] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/dfdae8cac650459a8378d17a14de9106 is 2.154ms for 6 entries.87alpha # [6727659.258085] alpha systemd-journald[87]: System Journal (/var/log/journal/dfdae8cac650459a8378d17a14de9106) is 8M, max 4G, 3.9G free.88alpha # [6727659.258288] alpha systemd[1]: Listening on Boot Loader Control Service Socket.89alpha # [6727659.258336] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container90alpha # [6727659.259255] alpha systemd[1]: Starting Save Transient machine-id to Disk...91alpha # [6727659.259290] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys92alpha # [6727659.452739] alpha systemd[1]: Finished Firewall.93alpha # [6727659.453046] alpha systemd[1]: Finished Flush Journal to Persistent Storage.94alpha # [6727659.453875] alpha systemd[1]: Reached target Preparation for Network.95alpha # [6727659.454278] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.96alpha # [6727659.455356] alpha systemd[1]: Starting Network Management...97alpha # [6727659.456049] alpha systemd[1]: Starting Create System Files and Directories...98alpha # [6727659.471806] alpha systemd-tmpfiles[197]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted99alpha # [6727659.471989] alpha systemd-tmpfiles[197]: fchmod() of /var/log/journal failed: Operation not permitted100alpha # [6727659.472750] alpha systemd-tmpfiles[197]: fchmod() of /var/log/journal/dfdae8cac650459a8378d17a14de9106 failed: Operation not permitted101alpha # [6727659.472947] alpha systemd-tmpfiles[197]: fchmod() of /run/log/journal failed: Operation not permitted102alpha # [6727659.477643] alpha systemd[1]: Finished Create System Files and Directories.103alpha # [6727659.478886] alpha systemd[1]: Starting Rebuild Journal Catalog...104alpha # [6727659.479694] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...105alpha # [6727659.491012] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.106alpha # [6727659.499843] alpha systemd[1]: Finished Rebuild Journal Catalog.107alpha # [6727659.500885] alpha systemd[1]: Starting Update is Completed...108alpha # [6727659.512734] alpha systemd[1]: Finished Update is Completed.109alpha # [6727660.094520] alpha systemd-networkd[196]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted110alpha # [6727660.094622] alpha systemd-networkd[196]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted111alpha # [6727660.101572] alpha systemd-networkd[196]: /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.112alpha # [6727660.101735] alpha systemd-networkd[196]: /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.113alpha # [6727660.101920] alpha systemd-networkd[196]: lo: Link UP114alpha # [6727660.101924] alpha systemd-networkd[196]: lo: Gained carrier115alpha # [6727660.102132] alpha systemd-networkd[196]: eth1: Configuring with /etc/systemd/network/40-eth1.network.116alpha # [6727660.102556] alpha systemd[1]: Started Network Management.117alpha # [6727660.102639] alpha systemd-networkd[196]: eth1: Link UP118alpha # [6727660.102865] alpha systemd-networkd[196]: eth1: Gained carrier119alpha # [6727660.103636] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...120gamma # [6727659.246826] gamma systemd-journald[87]: Journal started121gamma # [6727659.246885] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/3ff398f145b448d6a3dd0f978ccec440) is 8M, max 2.5G, 2.4G free.122gamma # [6727659.256875] gamma systemd[1]: Starting Flush Journal to Persistent Storage...123gamma # [6727659.257372] gamma systemd[1]: Finished Create Static Device Nodes in /dev.124gamma # [6727659.258122] gamma systemd[1]: Reached target Preparation for Local File Systems.125gamma # [6727659.258240] gamma systemd[1]: Reached target Local File Systems.126gamma # [6727659.259053] gamma systemd[1]: Listening on Boot Loader Control Service Socket.127gamma # [6727659.259101] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container128gamma # [6727659.260270] gamma systemd[1]: Starting Save Transient machine-id to Disk...129gamma # [6727659.260315] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys130gamma # [6727659.266397] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/3ff398f145b448d6a3dd0f978ccec440 is 1.742ms for 10 entries.131gamma # [6727659.266397] gamma systemd-journald[87]: System Journal (/var/log/journal/3ff398f145b448d6a3dd0f978ccec440) is 8M, max 4G, 3.9G free.132gamma # [6727659.399718] gamma systemd[1]: Finished Firewall.133gamma # [6727659.399898] gamma systemd[1]: Reached target Preparation for Network.134gamma # [6727659.400159] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.135gamma # [6727659.401387] gamma systemd[1]: Starting Network Management...136gamma # [6727659.452373] gamma systemd[1]: Finished Flush Journal to Persistent Storage.137gamma # [6727659.453958] gamma systemd[1]: Starting Create System Files and Directories...138gamma # [6727659.472868] gamma systemd-tmpfiles[198]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted139gamma # [6727659.473082] gamma systemd-tmpfiles[198]: fchmod() of /var/log/journal failed: Operation not permitted140gamma # [6727659.473222] gamma systemd-tmpfiles[198]: fchmod() of /var/log/journal/3ff398f145b448d6a3dd0f978ccec440 failed: Operation not permitted141gamma # [6727659.473455] gamma systemd-tmpfiles[198]: fchmod() of /run/log/journal failed: Operation not permitted142gamma # [6727659.477699] gamma systemd[1]: Finished Create System Files and Directories.143gamma # [6727659.478904] gamma systemd[1]: Starting Rebuild Journal Catalog...144gamma # [6727659.479693] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...145gamma # [6727659.490991] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.146gamma # [6727659.499673] gamma systemd[1]: Finished Rebuild Journal Catalog.147gamma # [6727659.500845] gamma systemd[1]: Starting Update is Completed...148gamma # [6727659.512089] gamma systemd[1]: Finished Update is Completed.149gamma # [6727660.067044] gamma systemd-networkd[194]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted150gamma # [6727660.067137] gamma systemd-networkd[194]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted151gamma # [6727660.074125] gamma systemd-networkd[194]: /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.152gamma # [6727660.074296] gamma systemd-networkd[194]: /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.153gamma # [6727660.074480] gamma systemd-networkd[194]: lo: Link UP154gamma # [6727660.074484] gamma systemd-networkd[194]: lo: Gained carrier155gamma # [6727660.074699] gamma systemd-networkd[194]: eth1: Configuring with /etc/systemd/network/40-eth1.network.156gamma # [6727660.075191] gamma systemd[1]: Started Network Management.157gamma # [6727660.075223] gamma systemd-networkd[194]: eth1: Link UP158gamma # [6727660.075481] gamma systemd-networkd[194]: eth1: Gained carrier159gamma # [6727660.076374] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...160gamma # [6727660.166144] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.161beta # [6727660.188518] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.162alpha # [6727660.193100] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.163alpha # [6727660.308798] alpha systemd-resolved[109]: Positive Trust Anchors:164alpha # [6727660.308817] alpha systemd-resolved[109]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d165alpha # [6727660.308821] alpha systemd-resolved[109]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16166alpha # [6727660.308858] alpha systemd-resolved[109]: 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 test167alpha # [6727660.386737] alpha systemd-resolved[109]: Using system hostname 'alpha'.168alpha # [6727660.392281] alpha systemd[1]: Started Network Name Resolution.169alpha # [6727660.392431] alpha systemd[1]: Reached target Network.170alpha # [6727660.392535] alpha systemd[1]: Reached target System Initialization.171alpha # [6727660.392583] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container172alpha # [6727660.392618] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.173alpha # [6727660.392636] alpha systemd[1]: Reached target Timer Units.174alpha # [6727660.392811] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.175alpha # [6727660.392967] alpha systemd[1]: Listening on Nix Daemon Socket.176alpha # [6727660.393080] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.177alpha # [6727660.393105] alpha systemd[1]: Reached target Socket Units.178alpha # [6727660.393149] alpha systemd[1]: Reached target Basic System.179alpha # [6727660.395997] alpha systemd[1]: Starting data mesher daemon...180alpha # [6727660.399929] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...181alpha # [6727660.403377] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...182alpha # [6727660.440243] alpha systemd[1]: Starting D-Bus System Message Bus...183alpha # [6727660.470886] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.184beta # [6727660.436530] beta systemd-resolved[118]: Positive Trust Anchors:185beta # [6727660.436555] beta systemd-resolved[118]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d186beta # [6727660.436558] beta systemd-resolved[118]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16187beta # [6727660.436596] beta systemd-resolved[118]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test188beta # [6727660.462764] beta systemd-resolved[118]: Using system hostname 'beta'.189beta # [6727660.465340] beta systemd[1]: Started Network Name Resolution.190beta # [6727660.465474] beta systemd[1]: Reached target Network.191beta # [6727660.465566] beta systemd[1]: Reached target System Initialization.192beta # [6727660.465621] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container193beta # [6727660.465653] beta systemd[1]: Started Daily Cleanup of Temporary Directories.194beta # [6727660.465676] beta systemd[1]: Reached target Timer Units.195beta # [6727660.465840] beta systemd[1]: Listening on D-Bus System Message Bus Socket.196beta # [6727660.465985] beta systemd[1]: Listening on Nix Daemon Socket.197beta # [6727660.466103] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.198beta # [6727660.466126] beta systemd[1]: Reached target Socket Units.199beta # [6727660.466171] beta systemd[1]: Reached target Basic System.200beta # [6727660.468901] beta systemd[1]: Starting data mesher daemon...201beta # [6727660.469992] beta systemd[1]: Starting Import lastlog data into lastlog2 database...202beta # [6727660.473697] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...203beta # [6727660.476169] beta systemd[1]: Starting D-Bus System Message Bus...204beta # [6727660.520683] beta systemd[1]: Finished Import lastlog data into lastlog2 database.205gamma # [6727660.765405] gamma systemd-resolved[110]: Positive Trust Anchors:206gamma # [6727660.765425] gamma systemd-resolved[110]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d207gamma # [6727660.765429] gamma systemd-resolved[110]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16208gamma # [6727660.765465] gamma systemd-resolved[110]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test209gamma # [6727660.823382] gamma systemd-resolved[110]: Using system hostname 'gamma'.210gamma # [6727660.832332] gamma systemd[1]: Started Network Name Resolution.211gamma # [6727660.832479] gamma systemd[1]: Reached target Network.212gamma # [6727660.832585] gamma systemd[1]: Reached target System Initialization.213gamma # [6727660.832639] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container214gamma # [6727660.832669] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.215gamma # [6727660.832688] gamma systemd[1]: Reached target Timer Units.216gamma # [6727660.832869] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.217gamma # [6727660.833042] gamma systemd[1]: Listening on Nix Daemon Socket.218gamma # [6727660.833177] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.219gamma # [6727660.833197] gamma systemd[1]: Reached target Socket Units.220gamma # [6727660.833237] gamma systemd[1]: Reached target Basic System.221gamma # [6727660.836222] gamma systemd[1]: Starting data mesher daemon...222gamma # [6727660.842662] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...223gamma # [6727660.948296] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...224gamma # [6727660.960342] gamma systemd[1]: Starting D-Bus System Message Bus...225gamma # [6727660.966322] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.226beta # [6727661.002485] beta systemd[1]: Started Name Service Cache Daemon (nsncd).227beta # [6727661.005866] beta nsncd[211]: Aug 25 20:11:27.055 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"228beta # [6727661.002591] beta systemd[1]: Reached target Host and Network Name Lookups.229beta # [6727661.002665] beta systemd[1]: Reached target User and Group Name Lookups.230beta # [6727661.006971] beta systemd[1]: Starting User Login Management...231beta # [6727661.008536] beta systemd[1]: Starting Permit User Sessions...232beta # [6727661.028214] beta systemd[1]: Finished Permit User Sessions.233beta # [6727661.029649] beta systemd[1]: Started Console Getty.234beta # [6727661.029716] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0235beta # [6727661.029736] beta systemd[1]: Reached target Login Prompts.236alpha # [6727661.828200] alpha systemd-networkd[196]: eth1: Gained IPv6LL237alpha # [6727661.972059] alpha nsncd[211]: Aug 25 20:11:28.023 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"238alpha # [6727661.970922] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).239alpha # [6727661.971002] alpha systemd[1]: Reached target Host and Network Name Lookups.240alpha # [6727661.971066] alpha systemd[1]: Reached target User and Group Name Lookups.241alpha # [6727661.972577] alpha systemd[1]: Starting User Login Management...242alpha # [6727661.973658] alpha systemd[1]: Starting Permit User Sessions...243alpha # [6727662.027694] alpha systemd[1]: Finished Permit User Sessions.244alpha # [6727662.029342] alpha systemd[1]: Started Console Getty.245alpha # [6727662.029407] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0246alpha # [6727662.029432] alpha systemd[1]: Reached target Login Prompts.247beta # [6727661.960189] beta systemd-networkd[196]: eth1: Gained IPv6LL248gamma # [6727662.056335] gamma systemd-networkd[194]: eth1: Gained IPv6LL249beta # [6727663.760988] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.250beta # [6727663.762763] beta systemd[1]: Finished Save Transient machine-id to Disk.251gamma # [6727663.870249] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.252gamma # [6727663.871444] gamma systemd[1]: Finished Save Transient machine-id to Disk.253alpha # [6727663.798704] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.254alpha # [6727663.799599] alpha systemd[1]: Finished Save Transient machine-id to Disk.255gamma # [6727664.227606] gamma nsncd[211]: Aug 25 20:11:30.280 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"256gamma # [6727664.227637] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).257gamma # [6727664.227725] gamma systemd[1]: Reached target Host and Network Name Lookups.258gamma # [6727664.227796] gamma systemd[1]: Reached target User and Group Name Lookups.259gamma # [6727664.229940] gamma systemd[1]: Starting User Login Management...260gamma # [6727664.231352] gamma systemd[1]: Starting Permit User Sessions...261gamma # [6727664.243033] gamma systemd[1]: Finished Permit User Sessions.262gamma # [6727664.248319] gamma systemd[1]: Started Console Getty.263gamma # [6727664.248426] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0264gamma # [6727664.248458] gamma systemd[1]: Reached target Login Prompts.265alpha # [6727664.987155] alpha dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...266alpha # [6727664.988626] alpha dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'267alpha # [6727664.988626] alpha dbus-broker-launch[213]: Invalid user-name in /nix/store/vvx1gcbxnhn7b09rflvf7fsv8czxw7hw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"268alpha # [6727664.988936] alpha systemd[1]: Started D-Bus System Message Bus.269alpha # [6727664.995986] alpha dbus-broker-launch[213]: Ready270beta # [6727664.969158] beta dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...271beta # [6727664.970048] beta dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'272beta # [6727664.970048] beta dbus-broker-launch[212]: Invalid user-name in /nix/store/vvx1gcbxnhn7b09rflvf7fsv8czxw7hw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"273beta # [6727664.970473] beta systemd[1]: Started D-Bus System Message Bus.274beta # [6727664.977921] beta dbus-broker-launch[212]: Ready275gamma # [6727665.112778] gamma dbus-broker-launch[216]: Looking up NSS user entry for 'systemd-timesync'...276gamma # [6727665.116686] gamma dbus-broker-launch[216]: NSS returned no entry for 'systemd-timesync'277gamma # [6727665.116686] gamma dbus-broker-launch[216]: Invalid user-name in /nix/store/vvx1gcbxnhn7b09rflvf7fsv8czxw7hw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"278gamma # [6727665.117539] gamma systemd[1]: Started D-Bus System Message Bus.279gamma # [6727665.124824] gamma dbus-broker-launch[216]: Ready280alpha # [6727665.464068] alpha data-mesher[209]: time=2026-08-25T20:11:31.517Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]281alpha # [6727665.465201] alpha data-mesher[209]: time=2026-08-25T20:11:31.518Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6: [/dns/alpha.clan/tcp/7946]} {12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6282alpha # [6727665.465237] alpha data-mesher[209]: time=2026-08-25T20:11:31.518Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml283alpha # [6727665.489443] alpha systemd-logind[223]: New seat seat0.284alpha # [6727665.489588] alpha systemd[1]: Started User Login Management.285alpha # [6727665.490967] alpha systemd[1]: Starting linger-users.service...286alpha # [6727665.501435] alpha systemd[1]: linger-users.service: Deactivated successfully.287alpha # [6727665.501576] alpha systemd[1]: Finished linger-users.service.288alpha # [6727665.501911] alpha data-mesher[209]: time=2026-08-25T20:11:31.555Z level=INFO msg="checking file integrity"289alpha # [6727665.502069] alpha data-mesher[209]: time=2026-08-25T20:11:31.555Z level=INFO msg="file integrity check complete"290alpha # [6727665.506363] alpha data-mesher[209]: time=2026-08-25T20:11:31.559Z level=INFO msg="libp2p host created" peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 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]"291alpha # [6727665.506409] alpha data-mesher[209]: time=2026-08-25T20:11:31.559Z level=INFO msg="registered HTTP route" method=GET path=/files292alpha # [6727665.506409] alpha data-mesher[209]: time=2026-08-25T20:11:31.559Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name293alpha # [6727665.506409] alpha data-mesher[209]: time=2026-08-25T20:11:31.559Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name294alpha # [6727665.506409] alpha data-mesher[209]: time=2026-08-25T20:11:31.559Z level=INFO msg="starting server"295alpha # [6727665.506832] alpha data-mesher[209]: time=2026-08-25T20:11:31.559Z level=INFO msg="HTTP server listening" address=[::1]:7331296alpha # [6727665.507061] alpha data-mesher[209]: time=2026-08-25T20:11:31.560Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331297alpha # [6727665.507308] alpha data-mesher[209]: time=2026-08-25T20:11:31.560Z level=INFO msg="waiting for DHT to populate" delay=10s298alpha # [6727665.513343] alpha data-mesher[209]: time=2026-08-25T20:11:31.566Z level=INFO msg="peer connected" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq remote_addr=/ip4/192.168.1.3/tcp/7946299alpha # [6727665.514563] alpha data-mesher[209]: time=2026-08-25T20:11:31.567Z level=INFO msg="peer connected" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 remote_addr=/ip6/2001:db8:1::2/tcp/7946300beta # [6727665.461370] beta data-mesher[209]: time=2026-08-25T20:11:31.514Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]301beta # [6727665.462900] beta data-mesher[209]: time=2026-08-25T20:11:31.515Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6: [/dns/alpha.clan/tcp/7946]} {12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3302beta # [6727665.462900] beta data-mesher[209]: time=2026-08-25T20:11:31.515Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml303beta # [6727665.486067] beta systemd-logind[228]: New seat seat0.304beta # [6727665.486907] beta systemd[1]: Started User Login Management.305beta # [6727665.489285] beta systemd[1]: Starting linger-users.service...306beta # [6727665.499592] beta data-mesher[209]: time=2026-08-25T20:11:31.552Z level=INFO msg="checking file integrity"307beta # [6727665.499727] beta data-mesher[209]: time=2026-08-25T20:11:31.552Z level=INFO msg="file integrity check complete"308beta # [6727665.502386] beta systemd[1]: linger-users.service: Deactivated successfully.309beta # [6727665.502537] beta systemd[1]: Finished linger-users.service.310beta # [6727665.504302] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="libp2p host created" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 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]"311beta # [6727665.504408] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name312beta # [6727665.504408] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="registered HTTP route" method=GET path=/files313beta # [6727665.504408] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name314beta # [6727665.504408] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="starting server"315beta # [6727665.504603] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="waiting for DHT to populate" delay=10s316beta # [6727665.504603] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="HTTP server listening" address=[::1]:7331317beta # [6727665.504603] beta data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331318beta # [6727665.515947] beta data-mesher[209]: time=2026-08-25T20:11:31.568Z level=INFO msg="peer connected" peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 remote_addr=/ip6/2001:db8:1::1/tcp/7946319beta # [6727665.520196] beta data-mesher[209]: time=2026-08-25T20:11:31.573Z level=INFO msg="peer connected" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq remote_addr=/ip4/192.168.1.3/tcp/7946320beta # [6727665.522664] beta data-mesher[209]: time=2026-08-25T20:11:31.575Z level=INFO msg="peer connected" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq remote_addr=/ip4/192.168.1.3/tcp/7946321beta # [6727665.522664] beta data-mesher[209]: time=2026-08-25T20:11:31.575Z level=INFO msg="peer connected" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq remote_addr=/ip6/2001:db8:1::3/tcp/7946322gamma # [6727665.465164] gamma data-mesher[209]: time=2026-08-25T20:11:31.518Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]323gamma # [6727665.466262] gamma data-mesher[209]: time=2026-08-25T20:11:31.519Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6: [/dns/alpha.clan/tcp/7946]} {12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq324gamma # [6727665.466262] gamma data-mesher[209]: time=2026-08-25T20:11:31.519Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml325gamma # [6727665.499509] gamma data-mesher[209]: time=2026-08-25T20:11:31.552Z level=INFO msg="checking file integrity"326gamma # [6727665.499632] gamma data-mesher[209]: time=2026-08-25T20:11:31.552Z level=INFO msg="file integrity check complete"327gamma # [6727665.504310] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="libp2p host created" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq 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]"328gamma # [6727665.504365] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name329gamma # [6727665.504365] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name330gamma # [6727665.504365] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="registered HTTP route" method=GET path=/files331gamma # [6727665.504365] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="starting server"332gamma # [6727665.504482] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="waiting for DHT to populate" delay=10s333gamma # [6727665.504546] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="HTTP server listening" address=[::1]:7331334gamma # [6727665.504578] gamma data-mesher[209]: time=2026-08-25T20:11:31.557Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331335gamma # [6727665.512686] gamma data-mesher[209]: time=2026-08-25T20:11:31.565Z level=INFO msg="peer connected" peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 remote_addr=/ip4/192.168.1.1/tcp/7946336gamma # [6727665.519283] gamma data-mesher[209]: time=2026-08-25T20:11:31.572Z level=INFO msg="peer connected" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 remote_addr=/ip4/192.168.1.2/tcp/7946337gamma # [6727665.523425] gamma data-mesher[209]: time=2026-08-25T20:11:31.576Z level=INFO msg="peer connected" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 remote_addr=/ip6/2001:db8:1::2/tcp/7946338gamma # [6727665.523547] gamma data-mesher[209]: time=2026-08-25T20:11:31.576Z level=INFO msg="peer connected" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 remote_addr=/ip4/192.168.1.2/tcp/50592339gamma # [6727665.555237] gamma systemd-logind[229]: New seat seat0.340gamma # [6727665.555435] gamma systemd[1]: Started User Login Management.341gamma # [6727665.557172] gamma systemd[1]: Starting linger-users.service...342gamma # [6727665.569980] gamma systemd[1]: linger-users.service: Deactivated successfully.343gamma # [6727665.570147] gamma systemd[1]: Finished linger-users.service.344alpha: still waiting for container 'alpha' to reach ready state...345alpha # [6727675.512182] alpha data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="performing state exchange with peers on join" count=1346alpha # [6727675.512182] alpha data-mesher[209]: time=2026-08-25T20:11:41.565Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s347alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq348alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq349alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3350alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3351alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.566Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3352alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.566Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s353alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.566Z level=INFO msg="server started"354alpha # [6727675.513476] alpha data-mesher[209]: time=2026-08-25T20:11:41.566Z level=INFO msg="starting expired-file sweeper" interval=1m0s355alpha # [6727675.513177] alpha systemd[1]: Started data mesher daemon.356alpha # [6727675.513398] alpha systemd[1]: Reached target Multi-User System.357alpha # [6727675.513553] alpha systemd[1]: Startup finished in 16.848s.358gamma # [6727675.506247] gamma data-mesher[209]: time=2026-08-25T20:11:41.559Z level=INFO msg="performing state exchange with peers on join" count=1359gamma # [6727675.506247] gamma data-mesher[209]: time=2026-08-25T20:11:41.559Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s360gamma # [6727675.512627] gamma data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6361gamma # [6727675.512627] gamma data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s362gamma # [6727675.512627] gamma data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="server started"363gamma # [6727675.513049] gamma data-mesher[209]: time=2026-08-25T20:11:41.566Z level=INFO msg="starting expired-file sweeper" interval=1m0s364gamma # [6727675.516163] gamma systemd[1]: Started data mesher daemon.365gamma # [6727675.516418] gamma systemd[1]: Reached target Multi-User System.366gamma # [6727675.516565] gamma systemd[1]: Startup finished in 16.835s.367beta # [6727675.505311] beta data-mesher[209]: time=2026-08-25T20:11:41.558Z level=INFO msg="performing state exchange with peers on join" count=1368beta # [6727675.505311] beta data-mesher[209]: time=2026-08-25T20:11:41.558Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s369beta # [6727675.512745] beta data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6370beta # [6727675.512745] beta data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6371beta # [6727675.512868] beta data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6372beta # [6727675.512868] beta data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s373beta # [6727675.512868] beta data-mesher[209]: time=2026-08-25T20:11:41.565Z level=INFO msg="server started"374beta # [6727675.512951] beta data-mesher[209]: time=2026-08-25T20:11:41.566Z level=INFO msg="starting expired-file sweeper" interval=1m0s375beta # [6727675.513014] beta systemd[1]: Started data mesher daemon.376beta # [6727675.513269] beta systemd[1]: Reached target Multi-User System.377beta # [6727675.513440] beta systemd[1]: Startup finished in 16.849s.378alpha: (finished: waiting for unit data-mesher.service, in 17.67 seconds)379beta: waiting for unit data-mesher.service380beta: (finished: waiting for unit data-mesher.service, in 0.01 seconds)381gamma: waiting for unit data-mesher.service382gamma: (finished: waiting for unit data-mesher.service, in 0.01 seconds)383alpha: must succeed: echo -n 'hello world' > /tmp/test_file384alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)385alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key386alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.13 seconds)387??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.388 File "/nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39389alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file390??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.391 File "/nix/store/8xlk88ia8b5yg9kfrg3skndwzgphipvr-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39392alpha: (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)393beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file394alpha # [6727676.003555] alpha data-mesher[209]: time=2026-08-25T20:11:42.056Z level=INFO msg=http_request uri=/files/test_file status=204395alpha # [6727680.513327] alpha data-mesher[209]: time=2026-08-25T20:11:46.566Z level=DEBUG msg="attempting push/pull" peer_count=2396alpha # [6727680.513327] alpha data-mesher[209]: time=2026-08-25T20:11:46.566Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s397alpha # [6727680.513825] alpha data-mesher[209]: time=2026-08-25T20:11:46.566Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3398alpha # [6727680.513825] alpha data-mesher[209]: time=2026-08-25T20:11:46.566Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3399alpha # [6727680.513960] alpha data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq400alpha # [6727680.513960] alpha data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq401alpha # [6727680.514298] alpha data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3402alpha # [6727680.514298] alpha data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s403alpha # [6727680.514372] alpha data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="received file request" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file404alpha # [6727680.514473] alpha data-mesher[209]: time=2026-08-25T20:11:46.567Z level=DEBUG msg="push/pull successful" interval=5s405alpha # [6727680.522774] alpha data-mesher[209]: time=2026-08-25T20:11:46.575Z level=INFO msg="file transfer complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file406alpha # [6727680.524319] alpha data-mesher[209]: time=2026-08-25T20:11:46.577Z level=INFO msg="received file request" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file407alpha # [6727680.547146] alpha data-mesher[209]: time=2026-08-25T20:11:46.577Z level=INFO msg="file transfer complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file408gamma # [6727680.513456] gamma data-mesher[209]: time=2026-08-25T20:11:46.566Z level=DEBUG msg="attempting push/pull" peer_count=2409gamma # [6727680.513456] gamma data-mesher[209]: time=2026-08-25T20:11:46.566Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s410gamma # [6727680.523386] gamma data-mesher[209]: time=2026-08-25T20:11:46.576Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6411gamma # [6727680.523386] gamma data-mesher[209]: time=2026-08-25T20:11:46.576Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_file412gamma # [6727680.523541] gamma data-mesher[209]: time=2026-08-25T20:11:46.576Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s413gamma # [6727680.523541] gamma data-mesher[209]: time=2026-08-25T20:11:46.576Z level=DEBUG msg="push/pull successful" interval=5s414gamma # [6727680.523541] gamma data-mesher[209]: time=2026-08-25T20:11:46.576Z level=INFO msg="scheduling file download" name=test_file415gamma # [6727680.523603] gamma data-mesher[209]: time=2026-08-25T20:11:46.576Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-25 20:11:41.957 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6416gamma # [6727680.751216] gamma data-mesher[209]: time=2026-08-25T20:11:46.804Z level=INFO msg="download complete" name=test_file signed_at="2026-08-25 20:11:41.957 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=227.638898ms417beta # [6727680.513327] beta data-mesher[209]: time=2026-08-25T20:11:46.566Z level=DEBUG msg="attempting push/pull" peer_count=2418beta # [6727680.513327] beta data-mesher[209]: time=2026-08-25T20:11:46.566Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s419beta # [6727680.513906] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6420beta # [6727680.513906] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6421beta # [6727680.513985] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_file422beta # [6727680.513985] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="scheduling file download" name=test_file423beta # [6727680.514021] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-25 20:11:41.957 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6424beta # [6727680.514303] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6425beta # [6727680.514303] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_file426beta # [6727680.514303] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s427beta # [6727680.514303] beta data-mesher[209]: time=2026-08-25T20:11:46.567Z level=DEBUG msg="push/pull successful" interval=5s428beta # [6727680.734114] beta data-mesher[209]: time=2026-08-25T20:11:46.787Z level=INFO msg="download complete" name=test_file signed_at="2026-08-25 20:11:41.957 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=220.065632ms429beta: (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)430gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file431gamma: (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)432beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key433beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.10 seconds)434alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file435beta # [6727681.166400] beta data-mesher[209]: time=2026-08-25T20:11:47.219Z level=INFO msg=http_request uri=/files/test_file status=204436alpha # [6727685.514632] alpha data-mesher[209]: time=2026-08-25T20:11:51.567Z level=DEBUG msg="attempting push/pull" peer_count=2437alpha # [6727685.514632] alpha data-mesher[209]: time=2026-08-25T20:11:51.567Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s438alpha # [6727685.515419] alpha data-mesher[209]: time=2026-08-25T20:11:51.568Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq439alpha # [6727685.515506] alpha data-mesher[209]: time=2026-08-25T20:11:51.568Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s440alpha # [6727685.515506] alpha data-mesher[209]: time=2026-08-25T20:11:51.568Z level=DEBUG msg="push/pull successful" interval=5s441beta # [6727685.514615] beta data-mesher[209]: time=2026-08-25T20:11:51.567Z level=DEBUG msg="attempting push/pull" peer_count=2442gamma # [6727685.515232] gamma data-mesher[209]: time=2026-08-25T20:11:51.568Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6443beta # [6727685.515084] beta data-mesher[209]: time=2026-08-25T20:11:51.567Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s444gamma # [6727685.515232] gamma data-mesher[209]: time=2026-08-25T20:11:51.568Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6445beta # [6727685.524200] beta data-mesher[209]: time=2026-08-25T20:11:51.577Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq446gamma # [6727685.515232] gamma data-mesher[209]: time=2026-08-25T20:11:51.568Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3447beta # [6727685.524200] beta data-mesher[209]: time=2026-08-25T20:11:51.577Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq448gamma # [6727685.515232] gamma data-mesher[209]: time=2026-08-25T20:11:51.568Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3449gamma # [6727685.523759] gamma data-mesher[209]: time=2026-08-25T20:11:51.576Z level=DEBUG msg="attempting push/pull" peer_count=2450gamma # [6727685.523876] gamma data-mesher[209]: time=2026-08-25T20:11:51.576Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s451gamma # [6727685.524382] gamma data-mesher[209]: time=2026-08-25T20:11:51.577Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3452gamma # [6727685.577158] gamma data-mesher[209]: time=2026-08-25T20:11:51.630Z level=DEBUG msg="imported tombstone" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 name=test_file written=true453gamma # [6727685.577298] gamma data-mesher[209]: time=2026-08-25T20:11:51.630Z level=DEBUG msg="imported tombstone" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 name=test_file written=false454gamma # [6727685.577298] gamma data-mesher[209]: time=2026-08-25T20:11:51.630Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s455gamma # [6727685.577298] gamma data-mesher[209]: time=2026-08-25T20:11:51.630Z level=DEBUG msg="push/pull successful" interval=5s456beta # [6727685.577509] beta data-mesher[209]: time=2026-08-25T20:11:51.630Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq457beta # [6727685.577509] beta data-mesher[209]: time=2026-08-25T20:11:51.630Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s458beta # [6727685.577668] beta data-mesher[209]: time=2026-08-25T20:11:51.630Z level=DEBUG msg="push/pull successful" interval=5s459alpha # [6727690.516670] alpha data-mesher[209]: time=2026-08-25T20:11:56.569Z level=DEBUG msg="attempting push/pull" peer_count=2460alpha # [6727690.516670] alpha data-mesher[209]: time=2026-08-25T20:11:56.569Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s461alpha # [6727690.517250] alpha data-mesher[209]: time=2026-08-25T20:11:56.570Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq462alpha # [6727690.579170] alpha data-mesher[209]: time=2026-08-25T20:11:56.632Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3463alpha # [6727690.579170] alpha data-mesher[209]: time=2026-08-25T20:11:56.632Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3464alpha # [6727690.604991] alpha data-mesher[209]: time=2026-08-25T20:11:56.658Z level=DEBUG msg="imported tombstone" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq name=test_file written=true465alpha # [6727690.604991] alpha data-mesher[209]: time=2026-08-25T20:11:56.658Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s466alpha # [6727690.605198] alpha data-mesher[209]: time=2026-08-25T20:11:56.658Z level=DEBUG msg="push/pull successful" interval=5s467alpha # [6727690.605198] alpha data-mesher[209]: time=2026-08-25T20:11:56.658Z level=DEBUG msg="imported tombstone" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 name=test_file written=false468gamma # [6727690.516953] gamma data-mesher[209]: time=2026-08-25T20:11:56.570Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6469beta # [6727690.578425] beta data-mesher[209]: time=2026-08-25T20:11:56.631Z level=DEBUG msg="attempting push/pull" peer_count=2470gamma # [6727690.516953] gamma data-mesher[209]: time=2026-08-25T20:11:56.570Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6471beta # [6727690.578425] beta data-mesher[209]: time=2026-08-25T20:11:56.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s472gamma # [6727690.578388] gamma data-mesher[209]: time=2026-08-25T20:11:56.631Z level=DEBUG msg="attempting push/pull" peer_count=2473gamma # [6727690.578528] gamma data-mesher[209]: time=2026-08-25T20:11:56.631Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s474gamma # [6727690.579383] gamma data-mesher[209]: time=2026-08-25T20:11:56.632Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3475gamma # [6727690.579421] gamma data-mesher[209]: time=2026-08-25T20:11:56.632Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s476gamma # [6727690.579479] gamma data-mesher[209]: time=2026-08-25T20:11:56.632Z level=DEBUG msg="push/pull successful" interval=5s477beta # [6727690.579236] beta data-mesher[209]: time=2026-08-25T20:11:56.632Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq478beta # [6727690.579236] beta data-mesher[209]: time=2026-08-25T20:11:56.632Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq479beta # [6727690.605296] beta data-mesher[209]: time=2026-08-25T20:11:56.658Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6480beta # [6727690.605396] beta data-mesher[209]: time=2026-08-25T20:11:56.658Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s481beta # [6727690.605396] beta data-mesher[209]: time=2026-08-25T20:11:56.658Z level=DEBUG msg="push/pull successful" interval=5s482alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.06 seconds)483beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file484beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)485gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file486gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)487alpha: must succeed: cat /nix/store/hw6512jcgmq95kz2zrg7yi21w3zia2rx-per-machine-alpha-data-mesher-node-identity_identity.pub488alpha: (finished: must succeed: cat /nix/store/hw6512jcgmq95kz2zrg7yi21w3zia2rx-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)489alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file490alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)491alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert492alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)493alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU494alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU, in 0.01 seconds)495beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU496alpha # [6727691.282155] alpha data-mesher[209]: time=2026-08-25T20:11:57.335Z level=INFO msg=http_request uri=/files/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU status=204497beta # [6727695.581741] beta data-mesher[209]: time=2026-08-25T20:12:01.634Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq498beta # [6727695.581741] beta data-mesher[209]: time=2026-08-25T20:12:01.634Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq499beta # [6727695.606879] beta data-mesher[209]: time=2026-08-25T20:12:01.660Z level=DEBUG msg="attempting push/pull" peer_count=2500beta # [6727695.606967] beta data-mesher[209]: time=2026-08-25T20:12:01.660Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s501beta # [6727695.608495] beta data-mesher[209]: time=2026-08-25T20:12:01.661Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6502beta # [6727695.608729] beta data-mesher[209]: time=2026-08-25T20:12:01.661Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU503beta # [6727695.608729] beta data-mesher[209]: time=2026-08-25T20:12:01.661Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s504beta # [6727695.608776] beta data-mesher[209]: time=2026-08-25T20:12:01.661Z level=DEBUG msg="push/pull successful" interval=5s505beta # [6727695.608825] beta data-mesher[209]: time=2026-08-25T20:12:01.661Z level=INFO msg="scheduling file download" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU506beta # [6727695.608870] beta data-mesher[209]: time=2026-08-25T20:12:01.662Z level=INFO msg="downloading file" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-25 20:11:57.333 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6507beta # [6727695.610946] beta data-mesher[209]: time=2026-08-25T20:12:01.664Z level=INFO msg="download complete" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-25 20:11:57.333 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=2.097189ms508alpha # [6727695.605673] alpha data-mesher[209]: time=2026-08-25T20:12:01.658Z level=DEBUG msg="attempting push/pull" peer_count=2509alpha # [6727695.606065] alpha data-mesher[209]: time=2026-08-25T20:12:01.658Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s510alpha # [6727695.606741] alpha data-mesher[209]: time=2026-08-25T20:12:01.659Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq511alpha # [6727695.606741] alpha data-mesher[209]: time=2026-08-25T20:12:01.659Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s512alpha # [6727695.606741] alpha data-mesher[209]: time=2026-08-25T20:12:01.659Z level=DEBUG msg="push/pull successful" interval=5s513alpha # [6727695.607701] alpha data-mesher[209]: time=2026-08-25T20:12:01.660Z level=INFO msg="received file request" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU514alpha # [6727695.607701] alpha data-mesher[209]: time=2026-08-25T20:12:01.660Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3515alpha # [6727695.607701] alpha data-mesher[209]: time=2026-08-25T20:12:01.660Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3516gamma # [6727695.580984] gamma data-mesher[209]: time=2026-08-25T20:12:01.633Z level=DEBUG msg="attempting push/pull" peer_count=2517gamma # [6727695.580984] gamma data-mesher[209]: time=2026-08-25T20:12:01.633Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s518alpha # [6727695.608617] alpha data-mesher[209]: time=2026-08-25T20:12:01.661Z level=INFO msg="file transfer complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU519gamma # [6727695.581918] gamma data-mesher[209]: time=2026-08-25T20:12:01.634Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3520alpha # [6727695.609143] alpha data-mesher[209]: time=2026-08-25T20:12:01.662Z level=INFO msg="received file request" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU521alpha # [6727695.609560] alpha data-mesher[209]: time=2026-08-25T20:12:01.662Z level=INFO msg="file transfer complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU522gamma # [6727695.581918] gamma data-mesher[209]: time=2026-08-25T20:12:01.634Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s523gamma # [6727695.581918] gamma data-mesher[209]: time=2026-08-25T20:12:01.635Z level=DEBUG msg="push/pull successful" interval=5s524gamma # [6727695.606611] gamma data-mesher[209]: time=2026-08-25T20:12:01.659Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6525gamma # [6727695.606611] gamma data-mesher[209]: time=2026-08-25T20:12:01.659Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6526gamma # [6727695.606611] gamma data-mesher[209]: time=2026-08-25T20:12:01.659Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU527gamma # [6727695.606611] gamma data-mesher[209]: time=2026-08-25T20:12:01.659Z level=INFO msg="scheduling file download" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU528gamma # [6727695.606782] gamma data-mesher[209]: time=2026-08-25T20:12:01.659Z level=INFO msg="downloading file" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-25 20:11:57.333 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6529gamma # [6727695.610709] gamma data-mesher[209]: time=2026-08-25T20:12:01.663Z level=INFO msg="download complete" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-25 20:11:57.333 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=4.042457ms530beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU, in 5.07 seconds)531gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU532gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU, in 0.01 seconds)533alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert534alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.00 seconds)535alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub536Error: failed to update file: 403 Forbidden, signer EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU= is not authorized for this file test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU537alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub, in 0.03 seconds)538(finished: run the VM test script, in 38.23 seconds)539alpha # [6727696.397152] alpha data-mesher[209]: time=2026-08-25T20:12:02.450Z level=INFO msg=http_request uri=/files/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU status=403540test script finished in 39.25s541cleanup542kill NspawnMachine (pid 53)543kill NspawnMachine (pid 54)544kill NspawnMachine (pid 55)545Container alpha terminated by signal KILL.546beta # [6727697.536879] beta systemd-networkd[196]: eth1: Link DOWN547gamma # [6727697.699899] gamma systemd-networkd[194]: eth1: Link DOWN548gamma # [6727697.699916] gamma systemd-networkd[194]: eth1: Lost carrier549gamma # [6727697.836830] gamma systemd-networkd[194]: eth1: Lost IPv6LL address fe80::b014:c2ff:fec2:f403.550Container beta terminated by signal KILL.551Container gamma terminated by signal KILL.552(finished: cleanup, in 0.85 seconds)