container-test-run-data-mesher
default.checks.aarch64-linux.data-mesher
· build #354
· 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 52)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(beta): TAP vde-tap1 not found; container will be isolated from VDE23nixos-nspawn(beta): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.24Note: 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.25░ Spawning container gamma on /build/vm-state-gamma.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.27nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE28nixos-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.29░ Spawning container beta on /build/vm-state-beta.30Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.31░ Spawning container alpha on /build/vm-state-alpha.32beta # [5740893.707998] beta systemd-journald[88]: Journal started33beta # [5740893.708059] beta systemd-journald[88]: Runtime Journal (/run/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa) is 8M, max 2.5G, 2.4G free.34beta # [5740893.711305] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.35beta # [5740893.719193] beta systemd[1]: Starting Flush Journal to Persistent Storage...36beta # [5740893.720039] beta systemd[1]: Starting Network Name Resolution...37beta # [5740893.720776] beta systemd[1]: Starting Create Static Device Nodes in /dev...38beta # [5740893.728176] beta systemd-journald[88]: Time spent on flushing to /var/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa is 2.284ms for 6 entries.39beta # [5740893.728176] beta systemd-journald[88]: System Journal (/var/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa) is 8M, max 4G, 3.9G free.40beta # [5740893.735052] beta systemd[1]: Finished Create Static Device Nodes in /dev.41beta # [5740893.735283] beta systemd[1]: Reached target Preparation for Local File Systems.42beta # [5740893.735368] beta systemd[1]: Reached target Local File Systems.43beta # [5740893.736088] beta systemd[1]: Listening on Boot Loader Control Service Socket.44gamma # [5740893.727083] gamma systemd-journald[87]: Journal started45beta # [5740893.736130] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container46gamma # [5740893.727132] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74) is 8M, max 2.5G, 2.4G free.47beta # [5740893.736939] beta systemd[1]: Starting Save Transient machine-id to Disk...48gamma # [5740893.732287] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.49beta # [5740893.736973] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys50gamma # [5740893.742964] gamma systemd[1]: Starting Flush Journal to Persistent Storage...51beta # [5740893.850884] beta systemd[1]: Finished Firewall.52gamma # [5740893.743691] gamma systemd[1]: Starting Network Name Resolution...53beta # [5740893.851029] beta systemd[1]: Reached target Preparation for Network.54gamma # [5740893.744348] gamma systemd[1]: Starting Create Static Device Nodes in /dev...55beta # [5740893.851254] beta systemd[1]: Listening on Network Management Resolve Hook Socket.56gamma # [5740893.751603] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74 is 1.948ms for 6 entries.57beta # [5740893.852362] beta systemd[1]: Starting Network Management...58gamma # [5740893.751603] gamma systemd-journald[87]: System Journal (/var/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74) is 8M, max 4G, 3.9G free.59beta # [5740893.892506] beta systemd[1]: Finished Flush Journal to Persistent Storage.60beta # [5740893.893796] beta systemd[1]: Starting Create System Files and Directories...61beta # [5740893.915894] beta systemd-tmpfiles[199]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted62beta # [5740893.916075] beta systemd-tmpfiles[199]: fchmod() of /var/log/journal failed: Operation not permitted63beta # [5740893.916191] beta systemd-tmpfiles[199]: fchmod() of /var/log/journal/1a987c3f6bf04357a8b2ed6a9dba9aaa failed: Operation not permitted64beta # [5740893.916367] beta systemd-tmpfiles[199]: fchmod() of /run/log/journal failed: Operation not permitted65beta # [5740893.917760] beta systemd[1]: Finished Create System Files and Directories.66beta # [5740893.918714] beta systemd[1]: Starting Rebuild Journal Catalog...67beta # [5740893.919361] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...68beta # [5740893.932193] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.69beta # [5740893.937898] beta systemd[1]: Finished Rebuild Journal Catalog.70beta # [5740893.938963] beta systemd[1]: Starting Update is Completed...71gamma # [5740893.757208] gamma systemd[1]: Finished Create Static Device Nodes in /dev.72gamma # [5740893.757447] gamma systemd[1]: Reached target Preparation for Local File Systems.73gamma # [5740893.757531] gamma systemd[1]: Reached target Local File Systems.74gamma # [5740893.758256] gamma systemd[1]: Listening on Boot Loader Control Service Socket.75gamma # [5740893.758294] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container76gamma # [5740893.759059] gamma systemd[1]: Starting Save Transient machine-id to Disk...77gamma # [5740893.759089] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys78gamma # [5740893.892488] gamma systemd[1]: Finished Flush Journal to Persistent Storage.79gamma # [5740893.892766] gamma systemd[1]: Finished Firewall.80gamma # [5740893.893345] gamma systemd[1]: Reached target Preparation for Network.81gamma # [5740893.893621] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.82gamma # [5740893.894641] gamma systemd[1]: Starting Network Management...83gamma # [5740893.895373] gamma systemd[1]: Starting Create System Files and Directories...84gamma # [5740893.915532] gamma systemd-tmpfiles[197]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted85gamma # [5740893.915728] gamma systemd-tmpfiles[197]: fchmod() of /var/log/journal failed: Operation not permitted86gamma # [5740893.915866] gamma systemd-tmpfiles[197]: fchmod() of /var/log/journal/ab15d3fe1a41491eb29b8e2e494c8c74 failed: Operation not permitted87gamma # [5740893.916180] gamma systemd-tmpfiles[197]: fchmod() of /run/log/journal failed: Operation not permitted88gamma # [5740893.917711] gamma systemd[1]: Finished Create System Files and Directories.89gamma # [5740893.918791] gamma systemd[1]: Starting Rebuild Journal Catalog...90gamma # [5740893.919444] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...91gamma # [5740893.931872] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.92alpha # [5740893.711990] alpha systemd-journald[87]: Journal started93alpha # [5740893.712141] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4) is 8M, max 2.5G, 2.4G free.94alpha # [5740893.720123] alpha systemd[1]: Starting Flush Journal to Persistent Storage...95alpha # [5740893.720938] alpha systemd[1]: Starting Network Name Resolution...96alpha # [5740893.721541] alpha systemd[1]: Starting Create Static Device Nodes in /dev...97alpha # [5740893.728162] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4 is 2.086ms for 5 entries.98alpha # [5740893.728162] alpha systemd-journald[87]: System Journal (/var/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4) is 8M, max 4G, 3.9G free.99alpha # [5740893.735516] alpha systemd[1]: Finished Create Static Device Nodes in /dev.100alpha # [5740893.735742] alpha systemd[1]: Reached target Preparation for Local File Systems.101alpha # [5740893.735824] alpha systemd[1]: Reached target Local File Systems.102alpha # [5740893.736530] alpha systemd[1]: Listening on Boot Loader Control Service Socket.103alpha # [5740893.736573] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container104alpha # [5740893.737314] alpha systemd[1]: Starting Save Transient machine-id to Disk...105alpha # [5740893.737348] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys106alpha # [5740893.850051] alpha systemd[1]: Finished Firewall.107alpha # [5740893.851062] alpha systemd[1]: Reached target Preparation for Network.108alpha # [5740893.851338] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.109alpha # [5740893.852361] alpha systemd[1]: Starting Network Management...110alpha # [5740893.892545] alpha systemd[1]: Finished Flush Journal to Persistent Storage.111alpha # [5740893.894177] alpha systemd[1]: Starting Create System Files and Directories...112alpha # [5740893.911182] alpha systemd-tmpfiles[198]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted113alpha # [5740893.911383] alpha systemd-tmpfiles[198]: fchmod() of /var/log/journal failed: Operation not permitted114alpha # [5740893.911522] alpha systemd-tmpfiles[198]: fchmod() of /var/log/journal/a8fc6305cabb4bc79c2c874d449ec9f4 failed: Operation not permitted115alpha # [5740893.911752] alpha systemd-tmpfiles[198]: fchmod() of /run/log/journal failed: Operation not permitted116alpha # [5740893.913712] alpha systemd[1]: Finished Create System Files and Directories.117alpha # [5740893.914971] alpha systemd[1]: Starting Rebuild Journal Catalog...118alpha # [5740893.915770] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...119alpha # [5740893.930735] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.120alpha # [5740893.936775] alpha systemd[1]: Finished Rebuild Journal Catalog.121alpha # [5740893.938500] alpha systemd[1]: Starting Update is Completed...122gamma # [5740893.942052] gamma systemd[1]: Finished Rebuild Journal Catalog.123beta # [5740893.950595] beta systemd[1]: Finished Update is Completed.124gamma # [5740893.943096] gamma systemd[1]: Starting Update is Completed...125gamma # [5740893.953798] gamma systemd[1]: Finished Update is Completed.126alpha # [5740893.950569] alpha systemd[1]: Finished Update is Completed.127beta # [5740894.265615] beta systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted128beta # [5740894.265715] beta systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted129beta # [5740894.272231] beta systemd-networkd[195]: /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 # [5740894.272391] beta systemd-networkd[195]: /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 # [5740894.272559] beta systemd-networkd[195]: lo: Link UP132beta # [5740894.272562] beta systemd-networkd[195]: lo: Gained carrier133beta # [5740894.272778] beta systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.134beta # [5740894.273295] beta systemd-networkd[195]: eth1: Link UP135beta # [5740894.273363] beta systemd[1]: Started Network Management.136beta # [5740894.273736] beta systemd-networkd[195]: eth1: Gained carrier137beta # [5740894.274381] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...138beta # [5740894.346657] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.139beta # [5740894.396787] beta systemd-resolved[111]: Positive Trust Anchors:140beta # [5740894.396799] beta systemd-resolved[111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d141beta # [5740894.396803] beta systemd-resolved[111]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16142beta # [5740894.396837] 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 test143beta # [5740894.419028] beta systemd-resolved[111]: Using system hostname 'beta'.144beta # [5740894.420465] beta systemd[1]: Started Network Name Resolution.145beta # [5740894.420554] beta systemd[1]: Reached target Network.146beta # [5740894.420617] beta systemd[1]: Reached target System Initialization.147beta # [5740894.420663] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container148beta # [5740894.420689] beta systemd[1]: Started Daily Cleanup of Temporary Directories.149beta # [5740894.420705] beta systemd[1]: Reached target Timer Units.150beta # [5740894.420818] beta systemd[1]: Listening on D-Bus System Message Bus Socket.151beta # [5740894.420928] beta systemd[1]: Listening on Nix Daemon Socket.152beta # [5740894.421027] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.153beta # [5740894.421046] beta systemd[1]: Reached target Socket Units.154beta # [5740894.421077] beta systemd[1]: Reached target Basic System.155beta # [5740894.476411] beta systemd[1]: Starting data mesher daemon...156beta # [5740894.477452] beta systemd[1]: Starting Import lastlog data into lastlog2 database...157beta # [5740894.478376] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...158beta # [5740894.479802] beta systemd[1]: Starting D-Bus System Message Bus...159beta # [5740894.495052] beta systemd[1]: Finished Import lastlog data into lastlog2 database.160gamma # [5740894.273242] gamma systemd-networkd[196]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted161gamma # [5740894.273862] gamma systemd-networkd[196]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted162gamma # [5740894.280462] gamma 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.163alpha # [5740894.266487] alpha systemd-networkd[194]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted164alpha # [5740894.266573] alpha systemd-networkd[194]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted165alpha # [5740894.272918] alpha 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.166alpha # [5740894.273082] alpha 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.167alpha # [5740894.273269] alpha systemd-networkd[194]: lo: Link UP168alpha # [5740894.273272] alpha systemd-networkd[194]: lo: Gained carrier169alpha # [5740894.273642] alpha systemd-networkd[194]: eth1: Configuring with /etc/systemd/network/40-eth1.network.170alpha # [5740894.274007] alpha systemd[1]: Started Network Management.171alpha # [5740894.274213] alpha systemd-networkd[194]: eth1: Link UP172alpha # [5740894.274396] alpha systemd-networkd[194]: eth1: Gained carrier173alpha # [5740894.274915] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...174gamma # [5740894.280623] gamma 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.175alpha # [5740894.349993] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.176gamma # [5740894.280777] gamma systemd-networkd[196]: lo: Link UP177alpha # [5740894.389343] alpha systemd-resolved[112]: Positive Trust Anchors:178gamma # [5740894.280781] gamma systemd-networkd[196]: lo: Gained carrier179alpha # [5740894.389361] alpha systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d180gamma # [5740894.280982] gamma systemd-networkd[196]: eth1: Configuring with /etc/systemd/network/40-eth1.network.181alpha # [5740894.389365] alpha systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16182gamma # [5740894.281421] gamma systemd-networkd[196]: eth1: Link UP183alpha # [5740894.389400] alpha systemd-resolved[112]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test184gamma # [5740894.281551] gamma systemd[1]: Started Network Management.185alpha # [5740894.412244] alpha systemd-resolved[112]: Using system hostname 'alpha'.186gamma # [5740894.281646] gamma systemd-networkd[196]: eth1: Gained carrier187alpha # [5740894.413691] alpha systemd[1]: Started Network Name Resolution.188gamma # [5740894.282470] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...189alpha # [5740894.413775] alpha systemd[1]: Reached target Network.190gamma # [5740894.377494] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.191alpha # [5740894.413838] alpha systemd[1]: Reached target System Initialization.192gamma # [5740894.403140] gamma systemd-resolved[112]: Positive Trust Anchors:193alpha # [5740894.413885] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container194gamma # [5740894.403154] gamma systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d195alpha # [5740894.413916] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.196gamma # [5740894.403157] gamma systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16197alpha # [5740894.413962] alpha systemd[1]: Reached target Timer Units.198gamma # [5740894.403192] gamma systemd-resolved[112]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test199alpha # [5740894.414082] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.200gamma # [5740894.425644] gamma systemd-resolved[112]: Using system hostname 'gamma'.201alpha # [5740894.414306] alpha systemd[1]: Listening on Nix Daemon Socket.202gamma # [5740894.427035] gamma systemd[1]: Started Network Name Resolution.203alpha # [5740894.414416] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.204gamma # [5740894.427118] gamma systemd[1]: Reached target Network.205alpha # [5740894.414438] alpha systemd[1]: Reached target Socket Units.206gamma # [5740894.427180] gamma systemd[1]: Reached target System Initialization.207alpha # [5740894.414478] alpha systemd[1]: Reached target Basic System.208gamma # [5740894.427229] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container209alpha # [5740894.415671] alpha systemd[1]: Starting data mesher daemon...210gamma # [5740894.427254] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.211alpha # [5740894.416415] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...212gamma # [5740894.427273] gamma systemd[1]: Reached target Timer Units.213alpha # [5740894.417312] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...214gamma # [5740894.427376] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.215alpha # [5740894.418493] alpha systemd[1]: Starting D-Bus System Message Bus...216gamma # [5740894.427507] gamma systemd[1]: Listening on Nix Daemon Socket.217alpha # [5740894.492160] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.218gamma # [5740894.427612] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.219gamma # [5740894.427632] gamma systemd[1]: Reached target Socket Units.220gamma # [5740894.427674] gamma systemd[1]: Reached target Basic System.221gamma # [5740894.476476] gamma systemd[1]: Starting data mesher daemon...222gamma # [5740894.477421] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...223gamma # [5740894.478325] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...224gamma # [5740894.479850] gamma systemd[1]: Starting D-Bus System Message Bus...225gamma # [5740894.497976] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.226gamma # [5740894.623600] gamma nsncd[211]: Aug 14 10:05:20.676 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"227gamma # [5740894.623679] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).228gamma # [5740894.623740] gamma systemd[1]: Reached target Host and Network Name Lookups.229gamma # [5740894.623798] gamma systemd[1]: Reached target User and Group Name Lookups.230gamma # [5740894.644687] gamma systemd[1]: Starting User Login Management...231gamma # [5740894.645549] gamma systemd[1]: Starting Permit User Sessions...232gamma # [5740894.655353] gamma systemd[1]: Finished Permit User Sessions.233gamma # [5740894.656534] gamma systemd[1]: Started Console Getty.234gamma # [5740894.656579] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0235gamma # [5740894.656597] gamma systemd[1]: Reached target Login Prompts.236gamma # [5740894.709434] gamma dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...237gamma # [5740894.728832] gamma dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'238gamma # [5740894.728832] gamma dbus-broker-launch[212]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"239gamma # [5740894.729319] gamma systemd[1]: Started D-Bus System Message Bus.240gamma # [5740894.737725] gamma dbus-broker-launch[212]: Ready241alpha # [5740894.604594] alpha nsncd[211]: Aug 14 10:05:20.657 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"242alpha # [5740894.604752] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).243alpha # [5740894.604830] alpha systemd[1]: Reached target Host and Network Name Lookups.244alpha # [5740894.604891] alpha systemd[1]: Reached target User and Group Name Lookups.245alpha # [5740894.644448] alpha systemd[1]: Starting User Login Management...246alpha # [5740894.645427] alpha systemd[1]: Starting Permit User Sessions...247alpha # [5740894.654884] alpha systemd[1]: Finished Permit User Sessions.248alpha # [5740894.656541] alpha systemd[1]: Started Console Getty.249alpha # [5740894.656581] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0250alpha # [5740894.656600] alpha systemd[1]: Reached target Login Prompts.251alpha # [5740894.719419] alpha dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...252alpha # [5740894.720595] alpha dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'253alpha # [5740894.720633] alpha dbus-broker-launch[212]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"254alpha # [5740894.721127] alpha systemd[1]: Started D-Bus System Message Bus.255alpha # [5740894.728871] alpha dbus-broker-launch[212]: Ready256beta # [5740894.592372] beta nsncd[212]: Aug 14 10:05:20.645 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"257beta # [5740894.592485] beta systemd[1]: Started Name Service Cache Daemon (nsncd).258beta # [5740894.592553] beta systemd[1]: Reached target Host and Network Name Lookups.259beta # [5740894.592610] beta systemd[1]: Reached target User and Group Name Lookups.260beta # [5740894.593883] beta systemd[1]: Starting User Login Management...261beta # [5740894.594968] beta systemd[1]: Starting Permit User Sessions...262beta # [5740894.651441] beta systemd[1]: Finished Permit User Sessions.263beta # [5740894.652655] beta systemd[1]: Started Console Getty.264beta # [5740894.652711] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0265beta # [5740894.652728] beta systemd[1]: Reached target Login Prompts.266beta # [5740894.706030] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...267beta # [5740894.707041] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'268beta # [5740894.707041] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/s6948z852ab7abf1cbncpas005aknpg0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"269beta # [5740894.707458] beta systemd[1]: Started D-Bus System Message Bus.270beta # [5740894.718309] beta dbus-broker-launch[213]: Ready271beta # [5740894.997987] beta data-mesher[210]: time=2026-08-14T10:05:21.051Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]272beta # [5740894.999049] beta data-mesher[210]: time=2026-08-14T10:05:21.052Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4: [/dns/alpha.clan/tcp/7946]} {12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn273beta # [5740894.999049] beta data-mesher[210]: time=2026-08-14T10:05:21.052Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml274beta # [5740895.062149] beta data-mesher[210]: time=2026-08-14T10:05:21.115Z level=INFO msg="checking file integrity"275beta # [5740895.062244] beta data-mesher[210]: time=2026-08-14T10:05:21.115Z level=INFO msg="file integrity check complete"276beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="libp2p host created" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn 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]"277beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name278beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=GET path=/files279beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name280beta # [5740895.066462] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="starting server"281beta # [5740895.066706] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="waiting for DHT to populate" delay=10s282beta # [5740895.067396] beta data-mesher[210]: time=2026-08-14T10:05:21.119Z level=INFO msg="HTTP server listening" address=[::1]:7331283beta # [5740895.067719] beta data-mesher[210]: time=2026-08-14T10:05:21.120Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331284beta # [5740895.073502] beta data-mesher[210]: time=2026-08-14T10:05:21.126Z level=INFO msg="peer connected" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 remote_addr=/ip4/192.168.1.1/tcp/7946285beta # [5740895.073891] beta data-mesher[210]: time=2026-08-14T10:05:21.127Z level=INFO msg="peer connected" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 remote_addr=/ip4/192.168.1.1/tcp/7946286beta # [5740895.081544] beta data-mesher[210]: time=2026-08-14T10:05:21.134Z level=INFO msg="peer connected" peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR remote_addr=/ip4/192.168.1.3/tcp/7946287beta # [5740895.144347] beta systemd-logind[229]: New seat seat0.288beta # [5740895.144540] beta systemd[1]: Started User Login Management.289beta # [5740895.181883] beta systemd[1]: Starting linger-users.service...290beta # [5740895.196344] beta systemd[1]: linger-users.service: Deactivated successfully.291beta # [5740895.196421] beta systemd[1]: Finished linger-users.service.292gamma # [5740894.980872] gamma data-mesher[209]: time=2026-08-14T10:05:21.033Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]293gamma # [5740894.981980] gamma data-mesher[209]: time=2026-08-14T10:05:21.035Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4: [/dns/alpha.clan/tcp/7946]} {12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR294gamma # [5740894.981980] gamma data-mesher[209]: time=2026-08-14T10:05:21.035Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml295gamma # [5740895.062064] gamma data-mesher[209]: time=2026-08-14T10:05:21.115Z level=INFO msg="checking file integrity"296gamma # [5740895.062397] gamma data-mesher[209]: time=2026-08-14T10:05:21.115Z level=INFO msg="file integrity check complete"297gamma # [5740895.067368] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="libp2p host created" peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR 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]"298gamma # [5740895.067368] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="registered HTTP route" method=GET path=/files299gamma # [5740895.067493] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name300gamma # [5740895.067493] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name301gamma # [5740895.067493] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="starting server"302gamma # [5740895.067552] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="waiting for DHT to populate" delay=10s303gamma # [5740895.067626] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="HTTP server listening" address=[::1]:7331304gamma # [5740895.067626] gamma data-mesher[209]: time=2026-08-14T10:05:21.120Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331305gamma # [5740895.073902] gamma data-mesher[209]: time=2026-08-14T10:05:21.126Z level=INFO msg="peer connected" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 remote_addr=/ip4/192.168.1.1/tcp/7946306gamma # [5740895.080529] gamma data-mesher[209]: time=2026-08-14T10:05:21.133Z level=INFO msg="peer connected" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn remote_addr=/ip4/192.168.1.2/tcp/7946307gamma # [5740895.132493] gamma systemd-logind[228]: New seat seat0.308gamma # [5740895.132673] gamma systemd[1]: Started User Login Management.309gamma # [5740895.180942] gamma systemd[1]: Starting linger-users.service...310gamma # [5740895.193632] gamma systemd[1]: linger-users.service: Deactivated successfully.311gamma # [5740895.193788] gamma systemd[1]: Finished linger-users.service.312alpha # [5740894.981090] alpha data-mesher[209]: time=2026-08-14T10:05:21.034Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]313alpha # [5740894.982171] alpha data-mesher[209]: time=2026-08-14T10:05:21.035Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4: [/dns/alpha.clan/tcp/7946]} {12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4314alpha # [5740894.982171] alpha data-mesher[209]: time=2026-08-14T10:05:21.035Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml315alpha # [5740895.061851] alpha data-mesher[209]: time=2026-08-14T10:05:21.114Z level=INFO msg="checking file integrity"316alpha # [5740895.062305] alpha data-mesher[209]: time=2026-08-14T10:05:21.115Z level=INFO msg="file integrity check complete"317alpha # [5740895.066324] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="libp2p host created" peer_id=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 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]"318alpha # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=GET path=/files319alpha # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name320alpha # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name321alpha # [5740895.066444] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="starting server"322alpha # [5740895.066553] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="HTTP server listening" address=[::1]:7331323alpha # [5740895.066586] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331324alpha # [5740895.066586] alpha data-mesher[209]: time=2026-08-14T10:05:21.119Z level=INFO msg="waiting for DHT to populate" delay=10s325alpha # [5740895.072292] alpha data-mesher[209]: time=2026-08-14T10:05:21.125Z level=INFO msg="peer connected" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn remote_addr=/ip4/192.168.1.2/tcp/7946326alpha # [5740895.073958] alpha data-mesher[209]: time=2026-08-14T10:05:21.127Z level=INFO msg="peer connected" peer_id=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn remote_addr=/ip4/192.168.1.2/tcp/58246327alpha # [5740895.075730] alpha data-mesher[209]: time=2026-08-14T10:05:21.128Z level=INFO msg="peer connected" peer_id=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR remote_addr=/ip4/192.168.1.3/tcp/7946328alpha # [5740895.147582] alpha systemd-logind[228]: New seat seat0.329alpha # [5740895.147763] alpha systemd[1]: Started User Login Management.330alpha # [5740895.181020] alpha systemd[1]: Starting linger-users.service...331alpha # [5740895.196333] alpha systemd[1]: linger-users.service: Deactivated successfully.332alpha # [5740895.196402] alpha systemd[1]: Finished linger-users.service.333alpha # [5740895.808515] alpha systemd-networkd[194]: eth1: Gained IPv6LL334beta # [5740895.872219] beta systemd-networkd[195]: eth1: Gained IPv6LL335gamma # [5740895.972198] gamma systemd-networkd[196]: eth1: Gained IPv6LL336alpha # [5740896.153768] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.337alpha # [5740896.154938] alpha systemd[1]: Finished Save Transient machine-id to Disk.338beta # [5740896.197245] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.339beta # [5740896.198434] beta systemd[1]: Finished Save Transient machine-id to Disk.340gamma # [5740896.223649] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.341gamma # [5740896.256258] gamma systemd[1]: Finished Save Transient machine-id to Disk.342alpha: still waiting for container 'alpha' to reach ready state...343alpha: (finished: waiting for unit data-mesher.service, in 12.18 seconds)344beta: waiting for unit data-mesher.service345beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)346gamma: waiting for unit data-mesher.service347gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)348alpha: must succeed: echo -n 'hello world' > /tmp/test_file349alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)350alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key351alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.12 seconds)352??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.353 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39354alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file355??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.356 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39357alpha: (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)358beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file359beta # [5740905.067503] beta data-mesher[210]: time=2026-08-14T10:05:31.120Z level=INFO msg="performing state exchange with peers on join" count=1360beta # [5740905.067503] beta data-mesher[210]: time=2026-08-14T10:05:31.120Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s361beta # [5740905.068241] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4362beta # [5740905.068241] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s363beta # [5740905.068241] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="server started"364beta # [5740905.068380] beta data-mesher[210]: time=2026-08-14T10:05:31.121Z level=INFO msg="starting expired-file sweeper" interval=1m0s365beta # [5740905.068520] beta systemd[1]: Started data mesher daemon.366beta # [5740905.069073] beta systemd[1]: Reached target Multi-User System.367beta # [5740905.069468] beta systemd[1]: Startup finished in 11.701s.368gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="performing state exchange with peers on join" count=1369gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s370gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4371gamma # [5740905.068331] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4372gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4373gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s374gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.122Z level=INFO msg="server started"375gamma # [5740905.069199] gamma data-mesher[209]: time=2026-08-14T10:05:31.122Z level=INFO msg="starting expired-file sweeper" interval=1m0s376gamma # [5740905.069067] gamma systemd[1]: Started data mesher daemon.377gamma # [5740905.069371] gamma systemd[1]: Reached target Multi-User System.378gamma # [5740905.069555] gamma systemd[1]: Startup finished in 11.695s.379alpha # [5740905.067594] alpha data-mesher[209]: time=2026-08-14T10:05:31.120Z level=INFO msg="performing state exchange with peers on join" count=1380alpha # [5740905.067594] alpha data-mesher[209]: time=2026-08-14T10:05:31.120Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s381alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn382alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn383alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR384alpha # [5740905.068451] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s385alpha # [5740905.068653] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR386alpha # [5740905.068653] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR387alpha # [5740905.068653] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="server started"388alpha # [5740905.068827] alpha data-mesher[209]: time=2026-08-14T10:05:31.121Z level=INFO msg="starting expired-file sweeper" interval=1m0s389alpha # [5740905.068810] alpha systemd[1]: Started data mesher daemon.390alpha # [5740905.069123] alpha systemd[1]: Reached target Multi-User System.391alpha # [5740905.069327] alpha systemd[1]: Startup finished in 11.687s.392alpha # [5740905.274702] alpha data-mesher[209]: time=2026-08-14T10:05:31.327Z level=INFO msg=http_request uri=/files/test_file status=204393beta # [5740910.069274] beta data-mesher[210]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="attempting push/pull" peer_count=2394beta # [5740910.069683] beta data-mesher[210]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s395beta # [5740910.069938] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR396beta # [5740910.069990] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR397beta # [5740910.070191] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR398beta # [5740910.070191] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s399beta # [5740910.070191] beta data-mesher[210]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="push/pull successful" interval=5s400beta # [5740910.071545] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4401beta # [5740910.071545] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4402beta # [5740910.071727] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=DEBUG msg="new file detected" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 name=test_file name=test_file403beta # [5740910.071727] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="scheduling file download" name=test_file404beta # [5740910.071824] beta data-mesher[210]: time=2026-08-14T10:05:36.124Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4405beta # [5740910.090974] beta data-mesher[210]: time=2026-08-14T10:05:36.144Z level=INFO msg="download complete" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 written=true elapsed=19.223146ms406beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.05 seconds)407gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file408gamma # [5740910.069328] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="attempting push/pull" peer_count=2409gamma # [5740910.069849] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s410gamma # [5740910.069849] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn411gamma # [5740910.069849] gamma data-mesher[209]: time=2026-08-14T10:05:36.122Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn412gamma # [5740910.070180] gamma data-mesher[209]: time=2026-08-14T10:05:36.123Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn413gamma # [5740910.070180] gamma data-mesher[209]: time=2026-08-14T10:05:36.123Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s414gamma # [5740910.070180] gamma data-mesher[209]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="push/pull successful" interval=5s415alpha # [5740910.070564] alpha data-mesher[209]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="attempting push/pull" peer_count=2416alpha # [5740910.070904] alpha data-mesher[209]: time=2026-08-14T10:05:36.123Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s417alpha # [5740910.071912] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn418alpha # [5740910.071912] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s419alpha # [5740910.071975] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=DEBUG msg="push/pull successful" interval=5s420alpha # [5740910.072439] alpha data-mesher[209]: time=2026-08-14T10:05:36.125Z level=INFO msg="received file request" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_file421alpha # [5740910.074261] alpha data-mesher[209]: time=2026-08-14T10:05:36.127Z level=INFO msg="file transfer complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_file422beta # [5740915.070739] beta data-mesher[210]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="attempting push/pull" peer_count=2423beta # [5740915.071186] beta data-mesher[210]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s424beta # [5740915.071443] beta data-mesher[210]: time=2026-08-14T10:05:41.124Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR425beta # [5740915.071489] beta data-mesher[210]: time=2026-08-14T10:05:41.124Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR426beta # [5740915.072171] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR427beta # [5740915.072171] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s428beta # [5740915.072247] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="push/pull successful" interval=5s429beta # [5740915.072470] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="received file request" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_file430beta # [5740915.072835] beta data-mesher[210]: time=2026-08-14T10:05:41.125Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4431beta # [5740915.072868] beta data-mesher[210]: time=2026-08-14T10:05:41.126Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4432beta # [5740915.073743] beta data-mesher[210]: time=2026-08-14T10:05:41.126Z level=INFO msg="file transfer complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_file433gamma: (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)434beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key435gamma # [5740915.070746] gamma data-mesher[209]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="attempting push/pull" peer_count=2436gamma # [5740915.071189] gamma data-mesher[209]: time=2026-08-14T10:05:41.123Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s437gamma # [5740915.071511] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn438gamma # [5740915.071551] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn439gamma # [5740915.071586] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=DEBUG msg="new file detected" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file name=test_file440gamma # [5740915.071667] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="scheduling file download" name=test_file441gamma # [5740915.071731] gamma data-mesher[209]: time=2026-08-14T10:05:41.124Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn442gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn443gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="new file detected" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file name=test_file444gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s445gamma # [5740915.072189] gamma data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="push/pull successful" interval=5s446gamma # [5740915.101773] gamma data-mesher[209]: time=2026-08-14T10:05:41.154Z level=INFO msg="download complete" name=test_file signed_at="2026-08-14 10:05:31.229 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn written=true elapsed=30.042735ms447beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)448alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file449alpha # [5740915.072477] alpha data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="attempting push/pull" peer_count=2450alpha # [5740915.072477] alpha data-mesher[209]: time=2026-08-14T10:05:41.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s451alpha # [5740915.073494] alpha data-mesher[209]: time=2026-08-14T10:05:41.126Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn452alpha # [5740915.073533] alpha data-mesher[209]: time=2026-08-14T10:05:41.126Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s453alpha # [5740915.073557] alpha data-mesher[209]: time=2026-08-14T10:05:41.126Z level=DEBUG msg="push/pull successful" interval=5s454beta # [5740915.408878] beta data-mesher[210]: time=2026-08-14T10:05:41.462Z level=INFO msg=http_request uri=/files/test_file status=204455beta # [5740920.072545] beta data-mesher[210]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="attempting push/pull" peer_count=2456beta # [5740920.073208] beta data-mesher[210]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s457beta # [5740920.144970] beta data-mesher[210]: time=2026-08-14T10:05:46.198Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR458beta # [5740920.145040] beta data-mesher[210]: time=2026-08-14T10:05:46.198Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s459beta # [5740920.145040] beta data-mesher[210]: time=2026-08-14T10:05:46.198Z level=DEBUG msg="push/pull successful" interval=5s460gamma # [5740920.072463] gamma data-mesher[209]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="attempting push/pull" peer_count=2461gamma # [5740920.072463] gamma data-mesher[209]: time=2026-08-14T10:05:46.125Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s462gamma # [5740920.073199] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn463gamma # [5740920.073262] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn464gamma # [5740920.073405] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4465gamma # [5740920.073485] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s466gamma # [5740920.073485] gamma data-mesher[209]: time=2026-08-14T10:05:46.126Z level=DEBUG msg="push/pull successful" interval=5s467gamma # [5740920.074226] gamma data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4468gamma # [5740920.074226] gamma data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4469gamma # [5740920.144644] gamma data-mesher[209]: time=2026-08-14T10:05:46.197Z level=DEBUG msg="imported tombstone" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file written=true470alpha # [5740920.072998] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR471alpha # [5740920.072998] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR472alpha # [5740920.073714] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=DEBUG msg="attempting push/pull" peer_count=2473alpha # [5740920.073714] alpha data-mesher[209]: time=2026-08-14T10:05:46.126Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s474alpha # [5740920.074558] alpha data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR475alpha # [5740920.085319] alpha data-mesher[209]: time=2026-08-14T10:05:46.127Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s476alpha # [5740920.085319] alpha data-mesher[209]: time=2026-08-14T10:05:46.127Z level=DEBUG msg="push/pull successful" interval=5s477gamma # [5740925.074042] gamma data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="attempting push/pull" peer_count=2478gamma # [5740925.074745] gamma data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s479gamma # [5740925.077226] gamma data-mesher[209]: time=2026-08-14T10:05:51.130Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4480gamma # [5740925.077307] gamma data-mesher[209]: time=2026-08-14T10:05:51.130Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s481gamma # [5740925.077307] gamma data-mesher[209]: time=2026-08-14T10:05:51.130Z level=DEBUG msg="push/pull successful" interval=5s482gamma # [5740925.146598] gamma data-mesher[209]: time=2026-08-14T10:05:51.199Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn483gamma # [5740925.146598] gamma data-mesher[209]: time=2026-08-14T10:05:51.199Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn484alpha # [5740925.074702] alpha data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="attempting push/pull" peer_count=2485alpha # [5740925.074702] alpha data-mesher[209]: time=2026-08-14T10:05:51.127Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s486alpha # [5740925.075471] alpha data-mesher[209]: time=2026-08-14T10:05:51.127Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR487alpha # [5740925.075471] alpha data-mesher[209]: time=2026-08-14T10:05:51.128Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR488alpha # [5740925.075670] alpha data-mesher[209]: time=2026-08-14T10:05:51.128Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn489alpha # [5740925.076919] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z level=DEBUG msg="imported tombstone" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR name=test_file written=true490alpha # [5740925.077042] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z level=DEBUG msg="imported tombstone" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn name=test_file written=false491alpha # [5740925.077042] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s492alpha # [5740925.077153] alpha data-mesher[209]: time=2026-08-14T10:05:51.130Z level=DEBUG msg="push/pull successful" interval=5s493alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.09 seconds)494beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file495beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)496gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file497gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)498alpha: must succeed: cat /nix/store/7pbnci0jzjsc1c2ml5zg1pqxkwz1iffr-per-machine-alpha-data-mesher-node-identity_identity.pub499alpha: (finished: must succeed: cat /nix/store/7pbnci0jzjsc1c2ml5zg1pqxkwz1iffr-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)500alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file501alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)502alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert503alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)504alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc505alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc, in 0.01 seconds)506beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc507beta # [5740925.075291] beta data-mesher[210]: time=2026-08-14T10:05:51.128Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4508beta # [5740925.075291] beta data-mesher[210]: time=2026-08-14T10:05:51.128Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4509beta # [5740925.146004] beta data-mesher[210]: time=2026-08-14T10:05:51.199Z level=DEBUG msg="attempting push/pull" peer_count=2510beta # [5740925.146103] beta data-mesher[210]: time=2026-08-14T10:05:51.199Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s511beta # [5740925.146921] beta data-mesher[210]: time=2026-08-14T10:05:51.200Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR512beta # [5740925.146987] beta data-mesher[210]: time=2026-08-14T10:05:51.200Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s513beta # [5740925.147040] beta data-mesher[210]: time=2026-08-14T10:05:51.200Z level=DEBUG msg="push/pull successful" interval=5s514alpha # [5740925.555262] alpha data-mesher[209]: time=2026-08-14T10:05:51.608Z level=INFO msg=http_request uri=/files/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc status=204515gamma # [5740930.077627] gamma data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="attempting push/pull" peer_count=2516gamma # [5740930.078311] gamma data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="initiating state exchange" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s517gamma # [5740930.078777] gamma data-mesher[209]: time=2026-08-14T10:05:56.131Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4518gamma # [5740930.079395] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="new file detected" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 name=test_file name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc519gamma # [5740930.079395] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="state exchange complete" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 timeout=5s520gamma # [5740930.079541] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="scheduling file download" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc521gamma # [5740930.079541] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="push/pull successful" interval=5s522gamma # [5740930.079541] gamma data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="downloading file" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4523gamma # [5740930.083769] gamma data-mesher[209]: time=2026-08-14T10:05:56.136Z level=INFO msg="download complete" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 written=true elapsed=4.261339ms524gamma # [5740930.152032] gamma data-mesher[209]: time=2026-08-14T10:05:56.201Z level=INFO msg="received state sync from peer" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn525gamma # [5740930.152032] gamma data-mesher[209]: time=2026-08-14T10:05:56.201Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn526alpha # [5740930.077509] alpha data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="attempting push/pull" peer_count=2527alpha # [5740930.077509] alpha data-mesher[209]: time=2026-08-14T10:05:56.130Z level=DEBUG msg="initiating state exchange" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s528alpha # [5740930.078320] alpha data-mesher[209]: time=2026-08-14T10:05:56.131Z level=INFO msg="received state sync from peer" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR529alpha # [5740930.078320] alpha data-mesher[209]: time=2026-08-14T10:05:56.131Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR530alpha # [5740930.079202] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="merging remote state" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn531alpha # [5740930.079276] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="state exchange complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn timeout=5s532alpha # [5740930.079276] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="push/pull successful" interval=5s533alpha # [5740930.079627] alpha data-mesher[209]: time=2026-08-14T10:05:56.132Z level=INFO msg="received file request" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc534alpha # [5740930.080121] alpha data-mesher[209]: time=2026-08-14T10:05:56.133Z level=INFO msg="received file request" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc535alpha # [5740930.082095] alpha data-mesher[209]: time=2026-08-14T10:05:56.135Z level=INFO msg="file transfer complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc536alpha # [5740930.082231] alpha data-mesher[209]: time=2026-08-14T10:05:56.135Z level=INFO msg="file transfer complete" peer=12D3KooWNqUe8Qu64Ktp2MmY9mg9zWanr4R5MhH6kCWZqmuX3qUn network="TifSVXqcR4PFygTwbOgtDIMZRz7WOC2PIV/SOXoFtpM=" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc537beta # [5740930.078248] beta data-mesher[210]: time=2026-08-14T10:05:56.131Z level=INFO msg="received state sync from peer" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4538beta # [5740930.078248] beta data-mesher[210]: time=2026-08-14T10:05:56.131Z level=INFO msg="merging remote state" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4539beta # [5740930.078966] beta data-mesher[210]: time=2026-08-14T10:05:56.132Z level=DEBUG msg="new file detected" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 name=test_file name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc540beta # [5740930.079028] beta data-mesher[210]: time=2026-08-14T10:05:56.132Z level=INFO msg="scheduling file download" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc541beta # [5740930.079136] beta data-mesher[210]: time=2026-08-14T10:05:56.132Z level=INFO msg="downloading file" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4542beta # [5740930.083817] beta data-mesher[210]: time=2026-08-14T10:05:56.136Z level=INFO msg="download complete" name=test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc signed_at="2026-08-14 10:05:51.606 +0000 UTC" signed_by="iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc=" peer=12D3KooWK4HG3EDXoYkGnpPNa6TMTcAzkqwzirrECu5qAzXNMVT4 written=true elapsed=4.723625ms543beta # [5740930.147883] beta data-mesher[210]: time=2026-08-14T10:05:56.200Z level=DEBUG msg="attempting push/pull" peer_count=2544beta # [5740930.148072] beta data-mesher[210]: time=2026-08-14T10:05:56.201Z level=DEBUG msg="initiating state exchange" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s545beta # [5740930.149578] beta data-mesher[210]: time=2026-08-14T10:05:56.202Z level=INFO msg="merging remote state" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR546beta # [5740930.150196] beta data-mesher[210]: time=2026-08-14T10:05:56.203Z level=INFO msg="state exchange complete" peer=12D3KooWGMWkHQydaQC68SJ8UDT8htx99dT8LpeAG6PEHNxvFzUR timeout=5s547beta # [5740930.150260] beta data-mesher[210]: time=2026-08-14T10:05:56.203Z level=DEBUG msg="push/pull successful" interval=5s548beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc, in 5.05 seconds)549gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc550gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc, in 0.01 seconds)551alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert552alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)553alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub554Error: failed to update file: 403 Forbidden, signer iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc= is not authorized for this file test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc555alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/a0lc46lb0aq973vlivllzdxhfsxfbjdj-shared-data-mesher-network_network.pub, in 0.03 seconds)556(finished: run the VM test script, in 37.73 seconds)557test script finished in 37.90s558cleanup559kill NspawnMachine (pid 52)560kill NspawnMachine (pid 54)561alpha # [5740930.660419] alpha data-mesher[209]: time=2026-08-14T10:05:56.713Z level=INFO msg=http_request uri=/files/test_ns/iUnDbApvAUNL1vqsoRsLsJB5fMRlUbAq4VoHnpElTsc status=403562Container alpha terminated by signal KILL.563kill NspawnMachine (pid 55)564Container beta terminated by signal KILL.565Container gamma terminated by signal KILL.566(finished: cleanup, in 0.49 seconds)