container-test-run-data-mesher
checks.aarch64-linux.data-mesher
· build #454
· 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 VMs12beta: systemd-nspawn running (pid 54)13alpha: systemd-nspawn running (pid 52)14gamma: systemd-nspawn running (pid 56)15alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...16beta: Waiting for journal at /build/vm-state-beta/var/log/journal...17gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...18(finished: start all VMs, in 0.00 seconds)19alpha: waiting for unit data-mesher.service20nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE21nixos-nspawn(beta): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.22nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE23nixos-nspawn(alpha): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.24nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE25nixos-nspawn(gamma): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.26Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.27Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.28Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.29░ Spawning container alpha on /build/vm-state-alpha.30░ Spawning container beta on /build/vm-state-beta.31░ Spawning container gamma on /build/vm-state-gamma.32beta # No journal files were found.33beta # No journal boot entry found for the specified boot (+0).34alpha # No journal files were found.35alpha # No journal boot entry found for the specified boot (+0).36gamma # No journal files were found.37gamma # No journal boot entry found for the specified boot (+0).38beta # [6396297.391412] beta systemd-journald[87]: Journal started39beta # [6396297.391467] beta systemd-journald[87]: Runtime Journal (/run/log/journal/793e9c4df0cb480ea489541517a58497) is 8M, max 2.5G, 2.4G free.40beta # [6396297.399741] beta systemd[1]: Starting Flush Journal to Persistent Storage...41beta # [6396297.400562] beta systemd[1]: Starting Network Name Resolution...42beta # [6396297.401229] beta systemd[1]: Starting Create Static Device Nodes in /dev...43beta # [6396297.410583] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/793e9c4df0cb480ea489541517a58497 is 1.504ms for 5 entries.44beta # [6396297.410583] beta systemd-journald[87]: System Journal (/var/log/journal/793e9c4df0cb480ea489541517a58497) is 8M, max 4G, 3.9G free.45beta # [6396297.415639] beta systemd[1]: Finished Create Static Device Nodes in /dev.46beta # [6396297.416284] beta systemd[1]: Reached target Preparation for Local File Systems.47beta # [6396297.416647] beta systemd[1]: Reached target Local File Systems.48beta # [6396297.417456] beta systemd[1]: Listening on Boot Loader Control Service Socket.49beta # [6396297.417502] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container50beta # [6396297.418317] beta systemd[1]: Starting Save Transient machine-id to Disk...51alpha # [6396297.401336] alpha systemd-journald[87]: Journal started52alpha # [6396297.401393] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/2690fd33eedd42ce80487cfc5bfa977e) is 8M, max 2.5G, 2.4G free.53beta # [6396297.418350] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys54alpha # [6396297.407221] alpha systemd[1]: Starting Flush Journal to Persistent Storage...55beta # [6396297.443194] beta systemd[1]: Finished Flush Journal to Persistent Storage.56beta # [6396297.444749] beta systemd[1]: Starting Create System Files and Directories...57beta # [6396297.461639] beta systemd-tmpfiles[140]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted58beta # [6396297.461811] beta systemd-tmpfiles[140]: fchmod() of /var/log/journal failed: Operation not permitted59alpha # [6396297.407963] alpha systemd[1]: Starting Network Name Resolution...60beta # [6396297.461921] beta systemd-tmpfiles[140]: fchmod() of /var/log/journal/793e9c4df0cb480ea489541517a58497 failed: Operation not permitted61beta # [6396297.462090] beta systemd-tmpfiles[140]: fchmod() of /run/log/journal failed: Operation not permitted62beta # [6396297.463399] beta systemd[1]: Finished Create System Files and Directories.63beta # [6396297.464334] beta systemd[1]: Starting Rebuild Journal Catalog...64beta # [6396297.464976] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...65beta # [6396297.478454] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.66beta # [6396297.484446] beta systemd[1]: Finished Rebuild Journal Catalog.67beta # [6396297.485462] beta systemd[1]: Starting Update is Completed...68beta # [6396297.495091] beta systemd[1]: Finished Update is Completed.69beta # [6396297.535740] beta systemd[1]: Finished Firewall.70beta # [6396297.535880] beta systemd[1]: Reached target Preparation for Network.71beta # [6396297.536091] beta systemd[1]: Listening on Network Management Resolve Hook Socket.72beta # [6396297.537047] beta systemd[1]: Starting Network Management...73alpha # [6396297.408571] alpha systemd[1]: Starting Create Static Device Nodes in /dev...74beta # [6396297.919642] beta systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted75alpha # [6396297.418160] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/2690fd33eedd42ce80487cfc5bfa977e is 1.478ms for 5 entries.76beta # [6396297.919732] beta systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted77alpha # [6396297.418160] alpha systemd-journald[87]: System Journal (/var/log/journal/2690fd33eedd42ce80487cfc5bfa977e) is 8M, max 4G, 3.9G free.78beta # [6396297.928233] beta systemd-networkd[204]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.79alpha # [6396297.425285] alpha systemd[1]: Finished Create Static Device Nodes in /dev.80beta # [6396297.928397] beta systemd-networkd[204]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.81alpha # [6396297.425956] alpha systemd[1]: Reached target Preparation for Local File Systems.82beta # [6396297.928560] beta systemd-networkd[204]: lo: Link UP83alpha # [6396297.426077] alpha systemd[1]: Reached target Local File Systems.84beta # [6396297.928564] beta systemd-networkd[204]: lo: Gained carrier85alpha # [6396297.426890] alpha systemd[1]: Listening on Boot Loader Control Service Socket.86beta # [6396297.928752] beta systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.87alpha # [6396297.426937] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container88beta # [6396297.929229] beta systemd-networkd[204]: eth1: Link UP89alpha # [6396297.427768] alpha systemd[1]: Starting Save Transient machine-id to Disk...90beta # [6396297.929452] beta systemd-networkd[204]: eth1: Gained carrier91alpha # [6396297.427805] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys92beta # [6396297.930005] beta systemd[1]: Started Network Management.93alpha # [6396297.443999] alpha systemd[1]: Finished Flush Journal to Persistent Storage.94beta # [6396297.931598] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...95alpha # [6396297.445367] alpha systemd[1]: Starting Create System Files and Directories...96beta # [6396298.025895] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.97alpha # [6396297.460986] alpha systemd-tmpfiles[139]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted98beta # [6396298.047516] beta systemd-resolved[109]: Positive Trust Anchors:99alpha # [6396297.461156] alpha systemd-tmpfiles[139]: fchmod() of /var/log/journal failed: Operation not permitted100beta # [6396298.047528] beta systemd-resolved[109]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d101alpha # [6396297.461526] alpha systemd-tmpfiles[139]: fchmod() of /var/log/journal/2690fd33eedd42ce80487cfc5bfa977e failed: Operation not permitted102beta # [6396298.047531] beta systemd-resolved[109]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16103alpha # [6396297.461708] alpha systemd-tmpfiles[139]: fchmod() of /run/log/journal failed: Operation not permitted104beta # [6396298.047567] beta 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 test105alpha # [6396297.463189] alpha systemd[1]: Finished Create System Files and Directories.106beta # [6396298.070021] beta systemd-resolved[109]: Using system hostname 'beta'.107alpha # [6396297.464179] alpha systemd[1]: Starting Rebuild Journal Catalog...108beta # [6396298.071429] beta systemd[1]: Started Network Name Resolution.109alpha # [6396297.464953] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...110beta # [6396298.071494] beta systemd[1]: Reached target Network.111alpha # [6396297.477423] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.112beta # [6396298.071559] beta systemd[1]: Reached target System Initialization.113gamma # [6396297.391924] gamma systemd-journald[87]: Journal started114beta # [6396298.071603] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container115gamma # [6396297.391979] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/435dfc3a7c59464bbe7c9d5210205550) is 8M, max 2.5G, 2.4G free.116beta # [6396298.071625] beta systemd[1]: Started Daily Cleanup of Temporary Directories.117gamma # [6396297.400286] gamma systemd[1]: Starting Flush Journal to Persistent Storage...118alpha # [6396297.485295] alpha systemd[1]: Finished Rebuild Journal Catalog.119beta # [6396298.071641] beta systemd[1]: Reached target Timer Units.120gamma # [6396297.401284] gamma systemd[1]: Starting Network Name Resolution...121beta # [6396298.071751] beta systemd[1]: Listening on D-Bus System Message Bus Socket.122gamma # [6396297.401947] gamma systemd[1]: Starting Create Static Device Nodes in /dev...123alpha # [6396297.486624] alpha systemd[1]: Starting Update is Completed...124gamma # [6396297.408837] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/435dfc3a7c59464bbe7c9d5210205550 is 1.476ms for 5 entries.125alpha # [6396297.495513] alpha systemd[1]: Finished Update is Completed.126beta # [6396298.071863] beta systemd[1]: Listening on Nix Daemon Socket.127gamma # [6396297.408837] gamma systemd-journald[87]: System Journal (/var/log/journal/435dfc3a7c59464bbe7c9d5210205550) is 8M, max 4G, 3.9G free.128beta # [6396298.071964] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.129gamma # [6396297.415541] gamma systemd[1]: Finished Create Static Device Nodes in /dev.130alpha # [6396297.537807] alpha systemd[1]: Finished Firewall.131gamma # [6396297.416209] gamma systemd[1]: Reached target Preparation for Local File Systems.132beta # [6396298.071982] beta systemd[1]: Reached target Socket Units.133gamma # [6396297.416334] gamma systemd[1]: Reached target Local File Systems.134alpha # [6396297.537902] alpha systemd[1]: Reached target Preparation for Network.135gamma # [6396297.417130] gamma systemd[1]: Listening on Boot Loader Control Service Socket.136alpha # [6396297.538107] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.137beta # [6396298.072030] beta systemd[1]: Reached target Basic System.138gamma # [6396297.417174] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container139beta # [6396298.073228] beta systemd[1]: Starting data mesher daemon...140gamma # [6396297.418007] gamma systemd[1]: Starting Save Transient machine-id to Disk...141alpha # [6396297.539064] alpha systemd[1]: Starting Network Management...142gamma # [6396297.418042] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys143alpha # [6396297.913375] alpha systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted144beta # [6396298.073959] beta systemd[1]: Starting Import lastlog data into lastlog2 database...145gamma # [6396297.431375] gamma systemd[1]: Finished Flush Journal to Persistent Storage.146beta # [6396298.074807] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...147gamma # [6396297.432390] gamma systemd[1]: Starting Create System Files and Directories...148alpha # [6396297.913459] alpha systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted149gamma # [6396297.448565] gamma systemd-tmpfiles[137]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted150alpha # [6396297.920919] alpha systemd-networkd[204]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.151beta # [6396298.076039] beta systemd[1]: Starting D-Bus System Message Bus...152gamma # [6396297.448777] gamma systemd-tmpfiles[137]: fchmod() of /var/log/journal failed: Operation not permitted153alpha # [6396297.921088] alpha systemd-networkd[204]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.154gamma # [6396297.448917] gamma systemd-tmpfiles[137]: fchmod() of /var/log/journal/435dfc3a7c59464bbe7c9d5210205550 failed: Operation not permitted155alpha # [6396297.921229] alpha systemd-networkd[204]: lo: Link UP156gamma # [6396297.449122] gamma systemd-tmpfiles[137]: fchmod() of /run/log/journal failed: Operation not permitted157alpha # [6396297.921234] alpha systemd-networkd[204]: lo: Gained carrier158gamma # [6396297.450651] gamma systemd[1]: Finished Create System Files and Directories.159alpha # [6396297.921388] alpha systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.160gamma # [6396297.451856] gamma systemd[1]: Starting Rebuild Journal Catalog...161alpha # [6396297.922072] alpha systemd-networkd[204]: eth1: Link UP162gamma # [6396297.452616] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...163alpha # [6396297.922352] alpha systemd-networkd[204]: eth1: Gained carrier164gamma # [6396297.464573] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.165alpha # [6396297.922361] alpha systemd[1]: Started Network Management.166gamma # [6396297.471764] gamma systemd[1]: Finished Rebuild Journal Catalog.167alpha # [6396297.923390] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...168gamma # [6396297.472860] gamma systemd[1]: Starting Update is Completed...169alpha # [6396298.005541] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.170gamma # [6396297.482669] gamma systemd[1]: Finished Update is Completed.171alpha # [6396298.050840] alpha systemd-resolved[110]: Positive Trust Anchors:172gamma # [6396297.532432] gamma systemd[1]: Finished Firewall.173alpha # [6396298.050851] alpha systemd-resolved[110]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d174gamma # [6396297.532582] gamma systemd[1]: Reached target Preparation for Network.175alpha # [6396298.050854] alpha systemd-resolved[110]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16176gamma # [6396297.532793] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.177alpha # [6396298.050888] alpha 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 test178gamma # [6396297.533798] gamma systemd[1]: Starting Network Management...179alpha # [6396298.072998] alpha systemd-resolved[110]: Using system hostname 'alpha'.180gamma # [6396297.911599] gamma systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted181alpha # [6396298.074381] alpha systemd[1]: Started Network Name Resolution.182gamma # [6396297.911690] gamma systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted183alpha # [6396298.074470] alpha systemd[1]: Reached target Network.184gamma # [6396297.920401] gamma systemd-networkd[204]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.185alpha # [6396298.074541] alpha systemd[1]: Reached target System Initialization.186gamma # [6396297.920578] gamma systemd-networkd[204]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.187alpha # [6396298.074594] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container188gamma # [6396297.920799] gamma systemd-networkd[204]: lo: Link UP189alpha # [6396298.074623] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.190gamma # [6396297.920804] gamma systemd-networkd[204]: lo: Gained carrier191alpha # [6396298.074639] alpha systemd[1]: Reached target Timer Units.192gamma # [6396297.920984] gamma systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.193alpha # [6396298.074774] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.194gamma # [6396297.921536] gamma systemd[1]: Started Network Management.195alpha # [6396298.075341] alpha systemd[1]: Listening on Nix Daemon Socket.196gamma # [6396297.921648] gamma systemd-networkd[204]: eth1: Link UP197alpha # [6396298.075592] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.198gamma # [6396297.922136] gamma systemd-networkd[204]: eth1: Gained carrier199alpha # [6396298.075625] alpha systemd[1]: Reached target Socket Units.200gamma # [6396297.922756] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...201alpha # [6396298.075676] alpha systemd[1]: Reached target Basic System.202gamma # [6396298.009401] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.203alpha # [6396298.077078] alpha systemd[1]: Starting data mesher daemon...204gamma # [6396298.054569] gamma systemd-resolved[111]: Positive Trust Anchors:205gamma # [6396298.054581] gamma systemd-resolved[111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d206gamma # [6396298.054584] gamma systemd-resolved[111]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16207gamma # [6396298.054618] gamma 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 test208gamma # [6396298.076625] gamma systemd-resolved[111]: Using system hostname 'gamma'.209gamma # [6396298.077959] gamma systemd[1]: Started Network Name Resolution.210gamma # [6396298.078035] gamma systemd[1]: Reached target Network.211gamma # [6396298.078104] gamma systemd[1]: Reached target System Initialization.212gamma # [6396298.078151] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container213gamma # [6396298.078178] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.214gamma # [6396298.078195] gamma systemd[1]: Reached target Timer Units.215gamma # [6396298.078315] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.216gamma # [6396298.078418] gamma systemd[1]: Listening on Nix Daemon Socket.217gamma # [6396298.078518] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.218gamma # [6396298.078538] gamma systemd[1]: Reached target Socket Units.219gamma # [6396298.078572] gamma systemd[1]: Reached target Basic System.220alpha # [6396298.292455] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...221alpha # [6396298.293836] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...222alpha # [6396298.295313] alpha systemd[1]: Starting D-Bus System Message Bus...223alpha # [6396298.313711] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.224alpha # [6396298.495664] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).225alpha # [6396298.495736] alpha systemd[1]: Reached target Host and Network Name Lookups.226alpha # [6396298.496383] alpha nsncd[212]: Aug 22 00:08:44.549 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"227alpha # [6396298.495806] alpha systemd[1]: Reached target User and Group Name Lookups.228alpha # [6396298.502136] alpha systemd[1]: Starting User Login Management...229alpha # [6396298.503156] alpha systemd[1]: Starting Permit User Sessions...230alpha # [6396298.513692] alpha systemd[1]: Finished Permit User Sessions.231alpha # [6396298.515298] alpha systemd[1]: Started Console Getty.232alpha # [6396298.515379] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0233alpha # [6396298.515418] alpha systemd[1]: Reached target Login Prompts.234gamma # [6396298.292455] gamma systemd[1]: Starting data mesher daemon...235gamma # [6396298.293553] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...236gamma # [6396298.294544] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...237gamma # [6396298.295653] gamma systemd[1]: Starting D-Bus System Message Bus...238gamma # [6396298.315134] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.239gamma # [6396298.494638] gamma nsncd[211]: Aug 22 00:08:44.547 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"240gamma # [6396298.494647] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).241gamma # [6396298.494744] gamma systemd[1]: Reached target Host and Network Name Lookups.242gamma # [6396298.494843] gamma systemd[1]: Reached target User and Group Name Lookups.243gamma # [6396298.501715] gamma systemd[1]: Starting User Login Management...244gamma # [6396298.502637] gamma systemd[1]: Starting Permit User Sessions...245gamma # [6396298.512087] gamma systemd[1]: Finished Permit User Sessions.246gamma # [6396298.513450] gamma systemd[1]: Started Console Getty.247gamma # [6396298.513502] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0248gamma # [6396298.513521] gamma systemd[1]: Reached target Login Prompts.249beta # [6396298.307801] beta systemd[1]: Finished Import lastlog data into lastlog2 database.250beta # [6396298.483320] beta systemd[1]: Started Name Service Cache Daemon (nsncd).251beta # [6396298.483378] beta systemd[1]: Reached target Host and Network Name Lookups.252beta # [6396298.483752] beta nsncd[211]: Aug 22 00:08:44.536 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"253beta # [6396298.483431] beta systemd[1]: Reached target User and Group Name Lookups.254beta # [6396298.501246] beta systemd[1]: Starting User Login Management...255beta # [6396298.502076] beta systemd[1]: Starting Permit User Sessions...256beta # [6396298.513805] beta systemd[1]: Finished Permit User Sessions.257beta # [6396298.515481] beta systemd[1]: Started Console Getty.258beta # [6396298.515537] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0259beta # [6396298.515561] beta systemd[1]: Reached target Login Prompts.260gamma # [6396298.590009] gamma dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...261gamma # [6396298.592482] gamma dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'262gamma # [6396298.592482] gamma dbus-broker-launch[212]: Invalid user-name in /nix/store/r467fybq8imqxxx1sakqq32ay68d83ra-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"263gamma # [6396298.592974] gamma systemd[1]: Started D-Bus System Message Bus.264gamma # [6396298.600129] gamma dbus-broker-launch[212]: Ready265gamma # [6396298.684371] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.266gamma # [6396298.685586] gamma systemd[1]: Finished Save Transient machine-id to Disk.267beta # [6396298.607613] beta dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...268beta # [6396298.608457] beta dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'269beta # [6396298.608457] beta dbus-broker-launch[212]: Invalid user-name in /nix/store/r467fybq8imqxxx1sakqq32ay68d83ra-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"270beta # [6396298.608871] beta systemd[1]: Started D-Bus System Message Bus.271beta # [6396298.620193] beta dbus-broker-launch[212]: Ready272beta # [6396298.691584] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.273beta # [6396298.693541] beta systemd[1]: Finished Save Transient machine-id to Disk.274alpha # [6396298.600784] alpha dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...275alpha # [6396298.601467] alpha dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'276alpha # [6396298.601467] alpha dbus-broker-launch[213]: Invalid user-name in /nix/store/r467fybq8imqxxx1sakqq32ay68d83ra-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"277alpha # [6396298.602026] alpha systemd[1]: Started D-Bus System Message Bus.278alpha # [6396298.608899] alpha dbus-broker-launch[213]: Ready279alpha # [6396298.691923] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.280alpha # [6396298.693852] alpha systemd[1]: Finished Save Transient machine-id to Disk.281beta # [6396298.921734] beta data-mesher[209]: time=2026-08-22T00:08:44.974Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]282beta # [6396298.922752] beta data-mesher[209]: time=2026-08-22T00:08:44.975Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd: [/dns/alpha.clan/tcp/7946]} {12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3283beta # [6396298.922752] beta data-mesher[209]: time=2026-08-22T00:08:44.975Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml284beta # [6396298.924017] beta data-mesher[209]: time=2026-08-22T00:08:44.977Z level=INFO msg="checking file integrity"285beta # [6396298.924160] beta data-mesher[209]: time=2026-08-22T00:08:44.977Z level=INFO msg="file integrity check complete"286beta # [6396298.927986] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="libp2p host created" peer_id=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 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]"287beta # [6396298.928071] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="registered HTTP route" method=GET path=/files288beta # [6396298.928071] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name289beta # [6396298.928071] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name290beta # [6396298.928071] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="starting server"291gamma # [6396298.904465] gamma data-mesher[209]: time=2026-08-22T00:08:44.957Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]292beta # [6396298.928142] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="waiting for DHT to populate" delay=10s293gamma # [6396298.908187] gamma data-mesher[209]: time=2026-08-22T00:08:44.961Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd: [/dns/alpha.clan/tcp/7946]} {12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt294beta # [6396298.928220] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="HTTP server listening" address=[::1]:7331295gamma # [6396298.908243] gamma data-mesher[209]: time=2026-08-22T00:08:44.961Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml296beta # [6396298.928250] beta data-mesher[209]: time=2026-08-22T00:08:44.981Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331297gamma # [6396298.916166] gamma data-mesher[209]: time=2026-08-22T00:08:44.969Z level=INFO msg="checking file integrity"298beta # [6396298.986420] beta data-mesher[209]: time=2026-08-22T00:08:45.039Z level=INFO msg="peer connected" peer_id=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd remote_addr=/ip4/192.168.1.1/tcp/7946299gamma # [6396298.916316] gamma data-mesher[209]: time=2026-08-22T00:08:44.969Z level=INFO msg="file integrity check complete"300beta # [6396299.010886] beta systemd-logind[228]: New seat seat0.301gamma # [6396298.921734] gamma data-mesher[209]: time=2026-08-22T00:08:44.974Z level=INFO msg="libp2p host created" peer_id=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt 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]"302beta # [6396299.011086] beta systemd[1]: Started User Login Management.303gamma # [6396298.921809] gamma data-mesher[209]: time=2026-08-22T00:08:44.974Z level=INFO msg="registered HTTP route" method=GET path=/files304beta # [6396299.036878] beta systemd[1]: Starting linger-users.service...305gamma # [6396298.921809] gamma data-mesher[209]: time=2026-08-22T00:08:44.974Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name306beta # [6396299.048305] beta systemd[1]: linger-users.service: Deactivated successfully.307gamma # [6396298.921809] gamma data-mesher[209]: time=2026-08-22T00:08:44.974Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name308beta # [6396299.048412] beta systemd[1]: Finished linger-users.service.309gamma # [6396298.921809] gamma data-mesher[209]: time=2026-08-22T00:08:44.974Z level=INFO msg="starting server"310gamma # [6396298.921887] gamma data-mesher[209]: time=2026-08-22T00:08:44.975Z level=INFO msg="waiting for DHT to populate" delay=10s311gamma # [6396298.921974] gamma data-mesher[209]: time=2026-08-22T00:08:44.975Z level=INFO msg="HTTP server listening" address=[::1]:7331312gamma # [6396298.922012] gamma data-mesher[209]: time=2026-08-22T00:08:44.975Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331313gamma # [6396299.020150] gamma systemd-logind[228]: New seat seat0.314gamma # [6396299.020351] gamma systemd[1]: Started User Login Management.315gamma # [6396299.037150] gamma systemd[1]: Starting linger-users.service...316gamma # [6396299.047996] gamma systemd[1]: linger-users.service: Deactivated successfully.317gamma # [6396299.048176] gamma systemd[1]: Finished linger-users.service.318gamma # [6396299.072224] gamma systemd-networkd[204]: eth1: Gained IPv6LL319alpha # [6396298.973152] alpha data-mesher[209]: time=2026-08-22T00:08:45.026Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]320alpha # [6396298.974164] alpha data-mesher[209]: time=2026-08-22T00:08:45.027Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd: [/dns/alpha.clan/tcp/7946]} {12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd321alpha # [6396298.974164] alpha data-mesher[209]: time=2026-08-22T00:08:45.027Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml322alpha # [6396298.975719] alpha data-mesher[209]: time=2026-08-22T00:08:45.028Z level=INFO msg="checking file integrity"323alpha # [6396298.975828] alpha data-mesher[209]: time=2026-08-22T00:08:45.028Z level=INFO msg="file integrity check complete"324alpha # [6396298.979735] alpha data-mesher[209]: time=2026-08-22T00:08:45.032Z level=INFO msg="libp2p host created" peer_id=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd 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]"325alpha # [6396298.979810] alpha data-mesher[209]: time=2026-08-22T00:08:45.032Z level=INFO msg="registered HTTP route" method=GET path=/files326alpha # [6396298.979810] alpha data-mesher[209]: time=2026-08-22T00:08:45.032Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name327alpha # [6396298.979810] alpha data-mesher[209]: time=2026-08-22T00:08:45.032Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name328alpha # [6396298.979810] alpha data-mesher[209]: time=2026-08-22T00:08:45.032Z level=INFO msg="starting server"329alpha # [6396298.979990] alpha data-mesher[209]: time=2026-08-22T00:08:45.033Z level=INFO msg="waiting for DHT to populate" delay=10s330alpha # [6396298.979990] alpha data-mesher[209]: time=2026-08-22T00:08:45.033Z level=INFO msg="HTTP server listening" address=[::1]:7331331alpha # [6396298.980112] alpha data-mesher[209]: time=2026-08-22T00:08:45.033Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331332alpha # [6396298.985399] alpha data-mesher[209]: time=2026-08-22T00:08:45.038Z level=INFO msg="peer connected" peer_id=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 remote_addr=/ip4/192.168.1.2/tcp/7946333alpha # [6396299.013721] alpha systemd-logind[228]: New seat seat0.334alpha # [6396299.013896] alpha systemd[1]: Started User Login Management.335alpha # [6396299.036983] alpha systemd[1]: Starting linger-users.service...336alpha # [6396299.048335] alpha systemd[1]: linger-users.service: Deactivated successfully.337alpha # [6396299.048412] alpha systemd[1]: Finished linger-users.service.338alpha # [6396299.168224] alpha systemd-networkd[204]: eth1: Gained IPv6LL339beta # [6396299.616213] beta systemd-networkd[204]: eth1: Gained IPv6LL340gamma # [6396303.934180] gamma data-mesher[209]: time=2026-08-22T00:08:49.987Z level=INFO msg="peer connected" peer_id=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 remote_addr=/ip6/2001:db8:1::2/tcp/7946341gamma # [6396303.946686] gamma data-mesher[209]: time=2026-08-22T00:08:49.999Z level=INFO msg="peer connected" peer_id=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd remote_addr=/ip4/192.168.1.1/tcp/7946342beta # [6396303.935776] beta data-mesher[209]: time=2026-08-22T00:08:49.988Z level=INFO msg="peer connected" peer_id=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt remote_addr=/ip6/2001:db8:1::3/tcp/7946343alpha # [6396303.948526] alpha data-mesher[209]: time=2026-08-22T00:08:50.001Z level=INFO msg="peer connected" peer_id=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt remote_addr=/ip4/192.168.1.3/tcp/7946344alpha: still waiting for container 'alpha' to reach ready state...345gamma # [6396308.922053] gamma data-mesher[209]: time=2026-08-22T00:08:54.975Z level=INFO msg="performing state exchange with peers on join" count=1346gamma # [6396308.922053] gamma data-mesher[209]: time=2026-08-22T00:08:54.975Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s347gamma # [6396308.923170] gamma data-mesher[209]: time=2026-08-22T00:08:54.976Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3348gamma # [6396308.923170] gamma data-mesher[209]: time=2026-08-22T00:08:54.976Z level=INFO msg="state exchange complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s349gamma # [6396308.923170] gamma data-mesher[209]: time=2026-08-22T00:08:54.976Z level=INFO msg="server started"350gamma # [6396308.923170] gamma data-mesher[209]: time=2026-08-22T00:08:54.976Z level=INFO msg="starting expired-file sweeper" interval=1m0s351gamma # [6396308.923325] gamma systemd[1]: Started data mesher daemon.352gamma # [6396308.923669] gamma systemd[1]: Reached target Multi-User System.353gamma # [6396308.923893] gamma systemd[1]: Startup finished in 12.129s.354beta # [6396308.922746] beta data-mesher[209]: time=2026-08-22T00:08:54.975Z level=INFO msg="received state sync from peer" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt355beta # [6396308.922746] beta data-mesher[209]: time=2026-08-22T00:08:54.975Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt356beta # [6396308.928390] beta data-mesher[209]: time=2026-08-22T00:08:54.981Z level=INFO msg="performing state exchange with peers on join" count=1357beta # [6396308.928432] beta data-mesher[209]: time=2026-08-22T00:08:54.981Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s358beta # [6396308.928944] beta data-mesher[209]: time=2026-08-22T00:08:54.982Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd359beta # [6396308.928944] beta data-mesher[209]: time=2026-08-22T00:08:54.982Z level=INFO msg="state exchange complete" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s360beta # [6396308.928986] beta data-mesher[209]: time=2026-08-22T00:08:54.982Z level=INFO msg="server started"361beta # [6396308.929091] beta data-mesher[209]: time=2026-08-22T00:08:54.982Z level=INFO msg="starting expired-file sweeper" interval=1m0s362beta # [6396308.929154] beta systemd[1]: Started data mesher daemon.363beta # [6396308.929386] beta systemd[1]: Reached target Multi-User System.364beta # [6396308.929546] beta systemd[1]: Startup finished in 12.132s.365beta # [6396308.980740] beta data-mesher[209]: time=2026-08-22T00:08:55.033Z level=INFO msg="received state sync from peer" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd366beta # [6396308.980740] beta data-mesher[209]: time=2026-08-22T00:08:55.033Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd367alpha # [6396308.928841] alpha data-mesher[209]: time=2026-08-22T00:08:54.981Z level=INFO msg="received state sync from peer" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3368alpha # [6396308.928841] alpha data-mesher[209]: time=2026-08-22T00:08:54.982Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3369alpha # [6396308.980383] alpha data-mesher[209]: time=2026-08-22T00:08:55.033Z level=INFO msg="performing state exchange with peers on join" count=1370alpha # [6396308.980383] alpha data-mesher[209]: time=2026-08-22T00:08:55.033Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s371alpha # [6396308.980826] alpha data-mesher[209]: time=2026-08-22T00:08:55.033Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3372alpha # [6396308.980826] alpha data-mesher[209]: time=2026-08-22T00:08:55.033Z level=INFO msg="state exchange complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s373alpha # [6396308.980884] alpha data-mesher[209]: time=2026-08-22T00:08:55.034Z level=INFO msg="server started"374alpha # [6396308.981056] alpha data-mesher[209]: time=2026-08-22T00:08:55.034Z level=INFO msg="starting expired-file sweeper" interval=1m0s375alpha # [6396308.981103] alpha systemd[1]: Started data mesher daemon.376alpha # [6396308.981636] alpha systemd[1]: Reached target Multi-User System.377alpha # [6396308.982491] alpha systemd[1]: Startup finished in 12.185s.378alpha: (finished: waiting for unit data-mesher.service, in 13.16 seconds)379beta: waiting for unit data-mesher.service380beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)381gamma: waiting for unit data-mesher.service382gamma: (finished: waiting for unit data-mesher.service, in 0.02 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/hk2gmkyvzjcrm2031zsfvq073pqbhw94-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/hk2gmkyvzjcrm2031zsfvq073pqbhw94-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.04 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/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-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/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-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 # [6396309.508013] alpha data-mesher[209]: time=2026-08-22T00:08:55.561Z level=INFO msg=http_request uri=/files/test_file status=204395gamma # [6396313.924147] gamma data-mesher[209]: time=2026-08-22T00:08:59.977Z level=DEBUG msg="attempting push/pull" peer_count=2396gamma # [6396313.925763] gamma data-mesher[209]: time=2026-08-22T00:08:59.977Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s397gamma # [6396313.925763] gamma data-mesher[209]: time=2026-08-22T00:08:59.977Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3398gamma # [6396313.925763] gamma data-mesher[209]: time=2026-08-22T00:08:59.977Z level=INFO msg="state exchange complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s399gamma # [6396313.925763] gamma data-mesher[209]: time=2026-08-22T00:08:59.977Z level=DEBUG msg="push/pull successful" interval=5s400gamma # [6396313.929614] gamma data-mesher[209]: time=2026-08-22T00:08:59.982Z level=INFO msg="received state sync from peer" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3401gamma # [6396313.929614] gamma data-mesher[209]: time=2026-08-22T00:08:59.982Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3402gamma # [6396313.982499] gamma data-mesher[209]: time=2026-08-22T00:09:00.035Z level=INFO msg="received state sync from peer" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd403gamma # [6396313.982499] gamma data-mesher[209]: time=2026-08-22T00:09:00.035Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd404gamma # [6396313.982673] gamma data-mesher[209]: time=2026-08-22T00:09:00.035Z level=DEBUG msg="new file detected" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd name=test_file name=test_file405gamma # [6396313.982673] gamma data-mesher[209]: time=2026-08-22T00:09:00.035Z level=INFO msg="scheduling file download" name=test_file406gamma # [6396313.982673] gamma data-mesher[209]: time=2026-08-22T00:09:00.035Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-22 00:08:55.544 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd407gamma # [6396313.986454] gamma data-mesher[209]: time=2026-08-22T00:09:00.039Z level=INFO msg="download complete" name=test_file signed_at="2026-08-22 00:08:55.544 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd written=true elapsed=3.784292ms408beta # [6396313.924614] beta data-mesher[209]: time=2026-08-22T00:08:59.977Z level=INFO msg="received state sync from peer" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt409beta # [6396313.924614] beta data-mesher[209]: time=2026-08-22T00:08:59.977Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt410beta # [6396313.929157] beta data-mesher[209]: time=2026-08-22T00:08:59.982Z level=DEBUG msg="attempting push/pull" peer_count=2411beta # [6396313.929298] beta data-mesher[209]: time=2026-08-22T00:08:59.982Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s412beta # [6396313.929762] beta data-mesher[209]: time=2026-08-22T00:08:59.982Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt413beta # [6396313.929762] beta data-mesher[209]: time=2026-08-22T00:08:59.982Z level=INFO msg="state exchange complete" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s414beta # [6396313.929915] beta data-mesher[209]: time=2026-08-22T00:08:59.982Z level=DEBUG msg="push/pull successful" interval=5s415alpha # [6396313.981847] alpha data-mesher[209]: time=2026-08-22T00:09:00.034Z level=DEBUG msg="attempting push/pull" peer_count=2416alpha # [6396313.982238] alpha data-mesher[209]: time=2026-08-22T00:09:00.035Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s417alpha # [6396313.982659] alpha data-mesher[209]: time=2026-08-22T00:09:00.035Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt418alpha # [6396313.982659] alpha data-mesher[209]: time=2026-08-22T00:09:00.035Z level=INFO msg="state exchange complete" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s419alpha # [6396313.982722] alpha data-mesher[209]: time=2026-08-22T00:09:00.035Z level=DEBUG msg="push/pull successful" interval=5s420alpha # [6396313.982948] alpha data-mesher[209]: time=2026-08-22T00:09:00.036Z level=INFO msg="received file request" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_file421alpha # [6396313.985143] alpha data-mesher[209]: time=2026-08-22T00:09:00.038Z level=INFO msg="file transfer complete" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_file422gamma # [6396318.925463] gamma data-mesher[209]: time=2026-08-22T00:09:04.978Z level=DEBUG msg="attempting push/pull" peer_count=2423gamma # [6396318.925463] gamma data-mesher[209]: time=2026-08-22T00:09:04.978Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s424gamma # [6396318.926271] gamma data-mesher[209]: time=2026-08-22T00:09:04.979Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3425gamma # [6396318.926271] gamma data-mesher[209]: time=2026-08-22T00:09:04.979Z level=INFO msg="state exchange complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s426gamma # [6396318.926271] gamma data-mesher[209]: time=2026-08-22T00:09:04.979Z level=DEBUG msg="push/pull successful" interval=5s427gamma # [6396318.926608] gamma data-mesher[209]: time=2026-08-22T00:09:04.979Z level=INFO msg="received file request" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_file428gamma # [6396318.929187] gamma data-mesher[209]: time=2026-08-22T00:09:04.982Z level=INFO msg="file transfer complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_file429gamma # [6396318.930806] gamma data-mesher[209]: time=2026-08-22T00:09:04.983Z level=INFO msg="received state sync from peer" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3430gamma # [6396318.930806] gamma data-mesher[209]: time=2026-08-22T00:09:04.983Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3431beta # [6396318.926015] beta data-mesher[209]: time=2026-08-22T00:09:04.979Z level=INFO msg="received state sync from peer" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt432beta # [6396318.926015] beta data-mesher[209]: time=2026-08-22T00:09:04.979Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt433beta # [6396318.926502] beta data-mesher[209]: time=2026-08-22T00:09:04.979Z level=DEBUG msg="new file detected" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt name=test_file name=test_file434beta # [6396318.926502] beta data-mesher[209]: time=2026-08-22T00:09:04.979Z level=INFO msg="scheduling file download" name=test_file435beta # [6396318.926502] beta data-mesher[209]: time=2026-08-22T00:09:04.979Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-22 00:08:55.544 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt436beta # [6396318.930483] beta data-mesher[209]: time=2026-08-22T00:09:04.983Z level=DEBUG msg="attempting push/pull" peer_count=2437beta # [6396318.931292] beta data-mesher[209]: time=2026-08-22T00:09:04.983Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s438beta # [6396318.931292] beta data-mesher[209]: time=2026-08-22T00:09:04.984Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt439beta # [6396318.931292] beta data-mesher[209]: time=2026-08-22T00:09:04.984Z level=DEBUG msg="new file detected" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt name=test_file name=test_file440beta # [6396318.931292] beta data-mesher[209]: time=2026-08-22T00:09:04.984Z level=INFO msg="state exchange complete" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s441beta # [6396318.931292] beta data-mesher[209]: time=2026-08-22T00:09:04.984Z level=DEBUG msg="push/pull successful" interval=5s442beta # [6396318.942789] beta data-mesher[209]: time=2026-08-22T00:09:04.995Z level=INFO msg="download complete" name=test_file signed_at="2026-08-22 00:08:55.544 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt written=true elapsed=16.573308ms443beta # [6396318.984336] beta data-mesher[209]: time=2026-08-22T00:09:05.037Z level=INFO msg="received state sync from peer" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd444beta # [6396318.984336] beta data-mesher[209]: time=2026-08-22T00:09:05.037Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd445alpha # [6396318.983675] alpha data-mesher[209]: time=2026-08-22T00:09:05.036Z level=DEBUG msg="attempting push/pull" peer_count=2446alpha # [6396318.984076] alpha data-mesher[209]: time=2026-08-22T00:09:05.036Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s447alpha # [6396318.984803] alpha data-mesher[209]: time=2026-08-22T00:09:05.037Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3448alpha # [6396318.984846] alpha data-mesher[209]: time=2026-08-22T00:09:05.038Z level=INFO msg="state exchange complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s449alpha # [6396318.984906] alpha data-mesher[209]: time=2026-08-22T00:09:05.038Z level=DEBUG msg="push/pull successful" interval=5s450beta: (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 10.08 seconds)451gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file452gamma: (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)453beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/hk2gmkyvzjcrm2031zsfvq073pqbhw94-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key454beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/hk2gmkyvzjcrm2031zsfvq073pqbhw94-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.04 seconds)455alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file456beta # [6396319.645991] beta data-mesher[209]: time=2026-08-22T00:09:05.699Z level=INFO msg=http_request uri=/files/test_file status=204457gamma # [6396323.927331] gamma data-mesher[209]: time=2026-08-22T00:09:09.980Z level=DEBUG msg="attempting push/pull" peer_count=2458gamma # [6396323.928086] gamma data-mesher[209]: time=2026-08-22T00:09:09.980Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s459gamma # [6396323.928086] gamma data-mesher[209]: time=2026-08-22T00:09:09.981Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd460gamma # [6396323.928196] gamma data-mesher[209]: time=2026-08-22T00:09:09.981Z level=INFO msg="state exchange complete" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s461gamma # [6396323.928196] gamma data-mesher[209]: time=2026-08-22T00:09:09.981Z level=DEBUG msg="push/pull successful" interval=5s462gamma # [6396323.985408] gamma data-mesher[209]: time=2026-08-22T00:09:10.038Z level=INFO msg="received state sync from peer" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd463gamma # [6396323.985408] gamma data-mesher[209]: time=2026-08-22T00:09:10.038Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd464gamma # [6396323.997545] gamma data-mesher[209]: time=2026-08-22T00:09:10.050Z level=DEBUG msg="imported tombstone" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd name=test_file written=true465alpha # [6396323.927894] alpha data-mesher[209]: time=2026-08-22T00:09:09.981Z level=INFO msg="received state sync from peer" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt466alpha # [6396323.927894] alpha data-mesher[209]: time=2026-08-22T00:09:09.981Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt467alpha # [6396323.931792] alpha data-mesher[209]: time=2026-08-22T00:09:09.984Z level=INFO msg="received state sync from peer" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3468beta # [6396323.931338] beta data-mesher[209]: time=2026-08-22T00:09:09.984Z level=DEBUG msg="attempting push/pull" peer_count=2469alpha # [6396323.931792] alpha data-mesher[209]: time=2026-08-22T00:09:09.984Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3470alpha # [6396323.952070] alpha data-mesher[209]: time=2026-08-22T00:09:10.005Z level=DEBUG msg="imported tombstone" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 name=test_file written=true471alpha # [6396323.984954] alpha data-mesher[209]: time=2026-08-22T00:09:10.038Z level=DEBUG msg="attempting push/pull" peer_count=2472alpha # [6396323.985010] alpha data-mesher[209]: time=2026-08-22T00:09:10.038Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s473alpha # [6396323.997766] alpha data-mesher[209]: time=2026-08-22T00:09:10.050Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt474alpha # [6396323.997830] alpha data-mesher[209]: time=2026-08-22T00:09:10.050Z level=INFO msg="state exchange complete" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s475beta # [6396323.931744] beta data-mesher[209]: time=2026-08-22T00:09:09.984Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s476alpha # [6396323.997830] alpha data-mesher[209]: time=2026-08-22T00:09:10.050Z level=DEBUG msg="push/pull successful" interval=5s477beta # [6396323.952455] beta data-mesher[209]: time=2026-08-22T00:09:10.005Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd478beta # [6396323.952455] beta data-mesher[209]: time=2026-08-22T00:09:10.005Z level=INFO msg="state exchange complete" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s479beta # [6396323.952594] beta data-mesher[209]: time=2026-08-22T00:09:10.005Z level=DEBUG msg="push/pull successful" interval=5s480alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)481beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file482beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)483gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file484gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)485alpha: must succeed: cat /nix/store/7y84rgsq6a8ghl5m36l8kz67zdaicnyq-per-machine-alpha-data-mesher-node-identity_identity.pub486alpha: (finished: must succeed: cat /nix/store/7y84rgsq6a8ghl5m36l8kz67zdaicnyq-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)487alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file488alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)489alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/hk2gmkyvzjcrm2031zsfvq073pqbhw94-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert490alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/hk2gmkyvzjcrm2031zsfvq073pqbhw94-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)491alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14492alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14, in 0.01 seconds)493beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14494alpha # [6396324.739723] alpha data-mesher[209]: time=2026-08-22T00:09:10.792Z level=INFO msg=http_request uri=/files/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 status=204495gamma # [6396328.929194] gamma data-mesher[209]: time=2026-08-22T00:09:14.982Z level=DEBUG msg="attempting push/pull" peer_count=2496gamma # [6396328.929194] gamma data-mesher[209]: time=2026-08-22T00:09:14.982Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s497gamma # [6396328.930339] gamma data-mesher[209]: time=2026-08-22T00:09:14.983Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3498gamma # [6396328.930339] gamma data-mesher[209]: time=2026-08-22T00:09:14.983Z level=INFO msg="state exchange complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 timeout=5s499gamma # [6396328.930339] gamma data-mesher[209]: time=2026-08-22T00:09:14.983Z level=DEBUG msg="push/pull successful" interval=5s500gamma # [6396328.999235] gamma data-mesher[209]: time=2026-08-22T00:09:15.052Z level=INFO msg="received state sync from peer" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd501gamma # [6396328.999235] gamma data-mesher[209]: time=2026-08-22T00:09:15.052Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd502gamma # [6396328.999420] gamma data-mesher[209]: time=2026-08-22T00:09:15.052Z level=DEBUG msg="new file detected" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd name=test_file name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14503gamma # [6396328.999596] gamma data-mesher[209]: time=2026-08-22T00:09:15.052Z level=INFO msg="scheduling file download" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14504gamma # [6396328.999596] gamma data-mesher[209]: time=2026-08-22T00:09:15.052Z level=INFO msg="downloading file" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 signed_at="2026-08-22 00:09:10.79 +0000 UTC" signed_by="fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14=" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd505gamma # [6396329.002555] gamma data-mesher[209]: time=2026-08-22T00:09:15.055Z level=INFO msg="download complete" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 signed_at="2026-08-22 00:09:10.79 +0000 UTC" signed_by="fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14=" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd written=true elapsed=2.975001ms506beta # [6396328.929784] beta data-mesher[209]: time=2026-08-22T00:09:14.982Z level=INFO msg="received state sync from peer" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt507beta # [6396328.929784] beta data-mesher[209]: time=2026-08-22T00:09:14.982Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt508beta # [6396328.953317] beta data-mesher[209]: time=2026-08-22T00:09:15.006Z level=DEBUG msg="attempting push/pull" peer_count=2509beta # [6396328.953463] beta data-mesher[209]: time=2026-08-22T00:09:15.006Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s510beta # [6396328.954984] beta data-mesher[209]: time=2026-08-22T00:09:15.008Z level=INFO msg="merging remote state" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd511beta # [6396328.955611] beta data-mesher[209]: time=2026-08-22T00:09:15.008Z level=DEBUG msg="new file detected" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd name=test_file name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14512beta # [6396328.955655] beta data-mesher[209]: time=2026-08-22T00:09:15.008Z level=INFO msg="state exchange complete" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd timeout=5s513beta # [6396328.955655] beta data-mesher[209]: time=2026-08-22T00:09:15.008Z level=DEBUG msg="push/pull successful" interval=5s514beta # [6396328.955706] beta data-mesher[209]: time=2026-08-22T00:09:15.008Z level=INFO msg="scheduling file download" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14515beta # [6396328.955731] beta data-mesher[209]: time=2026-08-22T00:09:15.008Z level=INFO msg="downloading file" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 signed_at="2026-08-22 00:09:10.79 +0000 UTC" signed_by="fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14=" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd516beta # [6396328.957824] beta data-mesher[209]: time=2026-08-22T00:09:15.010Z level=INFO msg="download complete" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 signed_at="2026-08-22 00:09:10.79 +0000 UTC" signed_by="fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14=" peer=12D3KooWJCTrRc3yEBjh7s89fzskn8pryfWCS5STHX9eYCh5zhnd written=true elapsed=2.101709ms517alpha # [6396328.954289] alpha data-mesher[209]: time=2026-08-22T00:09:15.007Z level=INFO msg="received state sync from peer" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3518alpha # [6396328.954289] alpha data-mesher[209]: time=2026-08-22T00:09:15.007Z level=INFO msg="merging remote state" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3519alpha # [6396328.956166] alpha data-mesher[209]: time=2026-08-22T00:09:15.009Z level=INFO msg="received file request" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14520alpha # [6396328.957005] alpha data-mesher[209]: time=2026-08-22T00:09:15.010Z level=INFO msg="file transfer complete" peer=12D3KooWAovAHgmfbDnsxkCe22BYvAvPt3N54mFMHkKvPrWmSRs3 network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14521alpha # [6396328.998591] alpha data-mesher[209]: time=2026-08-22T00:09:15.051Z level=DEBUG msg="attempting push/pull" peer_count=2522alpha # [6396328.998728] alpha data-mesher[209]: time=2026-08-22T00:09:15.051Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s523alpha # [6396328.999632] alpha data-mesher[209]: time=2026-08-22T00:09:15.052Z level=INFO msg="merging remote state" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt524alpha # [6396328.999811] alpha data-mesher[209]: time=2026-08-22T00:09:15.052Z level=INFO msg="state exchange complete" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt timeout=5s525alpha # [6396328.999850] alpha data-mesher[209]: time=2026-08-22T00:09:15.053Z level=DEBUG msg="push/pull successful" interval=5s526alpha # [6396329.000108] alpha data-mesher[209]: time=2026-08-22T00:09:15.053Z level=INFO msg="received file request" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14527alpha # [6396329.001692] alpha data-mesher[209]: time=2026-08-22T00:09:15.054Z level=INFO msg="file transfer complete" peer=12D3KooWJTrTC7bS9PFqDxm2JQ6u2b9BCthABCKotUvEgMLJHcTt network="L6Pbpa5VKy9JW7S12yJLmmVhAeL70UK5O8uUwfHqkjY=" name=test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14528beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14, in 5.04 seconds)529gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14530gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14, in 0.01 seconds)531alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert532alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)533alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/hk2gmkyvzjcrm2031zsfvq073pqbhw94-shared-data-mesher-network_network.pub534Error: failed to update file: 403 Forbidden, signer fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14= is not authorized for this file test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14535alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/hk2gmkyvzjcrm2031zsfvq073pqbhw94-shared-data-mesher-network_network.pub, in 0.02 seconds)536(finished: run the VM test script, in 33.58 seconds)537test script finished in 33.84s538cleanup539kill NspawnMachine (pid 52)540alpha # [6396329.825906] alpha data-mesher[209]: time=2026-08-22T00:09:15.879Z level=INFO msg=http_request uri=/files/test_ns/fIazRqpZeStJedXnojG3Ntn8c7k04foCsxob8iUMt14 status=403541kill NspawnMachine (pid 54)542Container alpha terminated by signal KILL.543kill NspawnMachine (pid 56)544Container beta terminated by signal KILL.545(finished: cleanup, in 0.44 seconds)546Container gamma terminated by signal KILL.