container-test-run-data-mesher
checks.aarch64-linux.data-mesher
· build #538
· 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 53)13gamma: systemd-nspawn running (pid 54)14beta: Waiting for journal at /build/vm-state-beta/var/log/journal...15gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...16alpha: systemd-nspawn running (pid 59)17alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...18(finished: start all VMs, in 0.01 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.24nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE25nixos-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.26Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.27░ Spawning container gamma on /build/vm-state-gamma.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.29Note: 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.30░ Spawning container alpha on /build/vm-state-alpha.31░ Spawning container beta on /build/vm-state-beta.32gamma # No journal files were found.33gamma # 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).36beta # No journal files were found.37beta # No journal boot entry found for the specified boot (+0).38gamma # [7209083.794285] gamma systemd-journald[87]: Journal started39gamma # [7209083.794349] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/1a70a553aaa34cc39375cca0f70fdf02) is 8M, max 2.5G, 2.4G free.40gamma # [7209083.800430] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.41gamma # [7209083.810119] gamma systemd[1]: Starting Flush Journal to Persistent Storage...42gamma # [7209083.811082] gamma systemd[1]: Starting Network Name Resolution...43gamma # [7209083.811861] gamma systemd[1]: Starting Create Static Device Nodes in /dev...44gamma # [7209083.819898] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/1a70a553aaa34cc39375cca0f70fdf02 is 1.352ms for 6 entries.45gamma # [7209083.819898] gamma systemd-journald[87]: System Journal (/var/log/journal/1a70a553aaa34cc39375cca0f70fdf02) is 8M, max 4G, 3.9G free.46gamma # [7209083.829581] gamma systemd[1]: Finished Create Static Device Nodes in /dev.47gamma # [7209083.829872] gamma systemd[1]: Reached target Preparation for Local File Systems.48gamma # [7209083.829964] gamma systemd[1]: Reached target Local File Systems.49gamma # [7209083.830814] gamma systemd[1]: Listening on Boot Loader Control Service Socket.50gamma # [7209083.830871] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container51gamma # [7209083.831791] gamma systemd[1]: Starting Save Transient machine-id to Disk...52gamma # [7209083.831830] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys53gamma # [7209083.892278] gamma systemd[1]: Finished Flush Journal to Persistent Storage.54gamma # [7209083.893352] gamma systemd[1]: Starting Create System Files and Directories...55gamma # [7209083.913605] gamma systemd-tmpfiles[161]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted56gamma # [7209083.913784] gamma systemd-tmpfiles[161]: fchmod() of /var/log/journal failed: Operation not permitted57gamma # [7209083.913904] gamma systemd-tmpfiles[161]: fchmod() of /var/log/journal/1a70a553aaa34cc39375cca0f70fdf02 failed: Operation not permitted58gamma # [7209083.914098] gamma systemd-tmpfiles[161]: fchmod() of /run/log/journal failed: Operation not permitted59gamma # [7209083.915615] gamma systemd[1]: Finished Create System Files and Directories.60gamma # [7209083.916787] gamma systemd[1]: Starting Rebuild Journal Catalog...61gamma # [7209083.917555] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...62gamma # [7209083.929324] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.63gamma # [7209083.937337] gamma systemd[1]: Finished Rebuild Journal Catalog.64gamma # [7209083.938591] gamma systemd[1]: Starting Update is Completed...65gamma # [7209083.950324] gamma systemd[1]: Finished Update is Completed.66gamma # [7209083.954301] gamma systemd[1]: Finished Firewall.67gamma # [7209083.954463] gamma systemd[1]: Reached target Preparation for Network.68gamma # [7209083.954695] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.69gamma # [7209083.955740] gamma systemd[1]: Starting Network Management...70gamma # [7209084.187981] gamma systemd[1]: Finished Save Transient machine-id to Disk.71gamma # [7209084.791396] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.72alpha # [7209083.786359] alpha systemd-journald[87]: Journal started73alpha # [7209083.786428] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/9268d7b4a3de4190a549efd1007133a1) is 8M, max 2.5G, 2.4G free.74alpha # [7209083.790586] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.75alpha # [7209083.801735] alpha systemd[1]: Starting Flush Journal to Persistent Storage...76alpha # [7209083.802706] alpha systemd[1]: Starting Network Name Resolution...77alpha # [7209083.803556] alpha systemd[1]: Starting Create Static Device Nodes in /dev...78alpha # [7209083.811923] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/9268d7b4a3de4190a549efd1007133a1 is 1.118ms for 6 entries.79alpha # [7209083.811923] alpha systemd-journald[87]: System Journal (/var/log/journal/9268d7b4a3de4190a549efd1007133a1) is 8M, max 4G, 3.9G free.80alpha # [7209083.820399] alpha systemd[1]: Finished Create Static Device Nodes in /dev.81alpha # [7209083.820755] alpha systemd[1]: Reached target Preparation for Local File Systems.82alpha # [7209083.820955] alpha systemd[1]: Reached target Local File Systems.83alpha # [7209083.821843] alpha systemd[1]: Listening on Boot Loader Control Service Socket.84alpha # [7209083.821898] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container85alpha # [7209083.822910] alpha systemd[1]: Starting Save Transient machine-id to Disk...86alpha # [7209083.822949] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys87alpha # [7209083.892275] alpha systemd[1]: Finished Flush Journal to Persistent Storage.88alpha # [7209083.893378] alpha systemd[1]: Starting Create System Files and Directories...89alpha # [7209083.911442] alpha systemd-tmpfiles[162]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted90alpha # [7209083.911646] alpha systemd-tmpfiles[162]: fchmod() of /var/log/journal failed: Operation not permitted91alpha # [7209083.911781] alpha systemd-tmpfiles[162]: fchmod() of /var/log/journal/9268d7b4a3de4190a549efd1007133a1 failed: Operation not permitted92alpha # [7209083.911993] alpha systemd-tmpfiles[162]: fchmod() of /run/log/journal failed: Operation not permitted93alpha # [7209083.915520] alpha systemd[1]: Finished Create System Files and Directories.94alpha # [7209083.918279] alpha systemd[1]: Starting Rebuild Journal Catalog...95alpha # [7209083.919242] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...96alpha # [7209083.931449] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.97alpha # [7209083.936852] alpha systemd[1]: Finished Rebuild Journal Catalog.98alpha # [7209083.938017] alpha systemd[1]: Starting Update is Completed...99alpha # [7209083.950319] alpha systemd[1]: Finished Update is Completed.100alpha # [7209083.961417] alpha systemd[1]: Finished Firewall.101alpha # [7209083.961596] alpha systemd[1]: Reached target Preparation for Network.102alpha # [7209083.961827] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.103alpha # [7209083.963578] alpha systemd[1]: Starting Network Management...104alpha # [7209084.189161] alpha systemd[1]: Finished Save Transient machine-id to Disk.105alpha # [7209084.788755] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.106beta # [7209083.789145] beta systemd-journald[87]: Journal started107beta # [7209083.789211] beta systemd-journald[87]: Runtime Journal (/run/log/journal/3783d0ad7ffc4ad296209b14875c5171) is 8M, max 2.5G, 2.4G free.108beta # [7209083.790535] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.109beta # [7209083.801979] beta systemd[1]: Starting Flush Journal to Persistent Storage...110beta # [7209083.802985] beta systemd[1]: Starting Network Name Resolution...111beta # [7209083.807053] beta systemd[1]: Starting Create Static Device Nodes in /dev...112beta # [7209083.812054] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/3783d0ad7ffc4ad296209b14875c5171 is 1.162ms for 6 entries.113beta # [7209083.812054] beta systemd-journald[87]: System Journal (/var/log/journal/3783d0ad7ffc4ad296209b14875c5171) is 8M, max 4G, 3.9G free.114beta # [7209083.821705] beta systemd[1]: Finished Create Static Device Nodes in /dev.115beta # [7209083.822075] beta systemd[1]: Reached target Preparation for Local File Systems.116beta # [7209083.822161] beta systemd[1]: Reached target Local File Systems.117beta # [7209083.822904] beta systemd[1]: Listening on Boot Loader Control Service Socket.118beta # [7209083.822948] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container119beta # [7209083.823848] beta systemd[1]: Starting Save Transient machine-id to Disk...120beta # [7209083.823886] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys121beta # [7209083.887114] beta systemd[1]: Finished Flush Journal to Persistent Storage.122beta # [7209083.889001] beta systemd[1]: Starting Create System Files and Directories...123beta # [7209083.905681] beta systemd-tmpfiles[163]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted124beta # [7209083.905864] beta systemd-tmpfiles[163]: fchmod() of /var/log/journal failed: Operation not permitted125beta # [7209083.905980] beta systemd-tmpfiles[163]: fchmod() of /var/log/journal/3783d0ad7ffc4ad296209b14875c5171 failed: Operation not permitted126beta # [7209083.906158] beta systemd-tmpfiles[163]: fchmod() of /run/log/journal failed: Operation not permitted127beta # [7209083.907800] beta systemd[1]: Finished Create System Files and Directories.128beta # [7209083.909094] beta systemd[1]: Starting Rebuild Journal Catalog...129beta # [7209083.910005] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...130beta # [7209083.921988] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.131beta # [7209083.929628] beta systemd[1]: Finished Rebuild Journal Catalog.132beta # [7209083.930934] beta systemd[1]: Starting Update is Completed...133beta # [7209083.944334] beta systemd[1]: Finished Update is Completed.134beta # [7209083.950974] beta systemd[1]: Finished Firewall.135beta # [7209083.951143] beta systemd[1]: Reached target Preparation for Network.136beta # [7209083.951373] beta systemd[1]: Listening on Network Management Resolve Hook Socket.137beta # [7209083.952515] beta systemd[1]: Starting Network Management...138beta # [7209084.196371] beta systemd[1]: Finished Save Transient machine-id to Disk.139beta # [7209084.788577] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.140gamma # [7209084.893870] gamma systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted141gamma # [7209084.893975] gamma systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted142gamma # [7209084.900785] 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.143gamma # [7209084.900953] 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.144gamma # [7209084.901156] gamma systemd-networkd[204]: lo: Link UP145gamma # [7209084.901161] gamma systemd-networkd[204]: lo: Gained carrier146gamma # [7209084.901358] gamma systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.147gamma # [7209084.901777] gamma systemd[1]: Started Network Management.148gamma # [7209084.901860] gamma systemd-networkd[204]: eth1: Link UP149gamma # [7209084.902265] gamma systemd-networkd[204]: eth1: Gained carrier150gamma # [7209084.903119] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...151gamma # [7209084.963824] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.152beta # [7209084.893875] beta systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted153beta # [7209084.893985] beta systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted154beta # [7209084.900848] 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.155beta # [7209084.901016] 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.156beta # [7209084.901206] beta systemd-networkd[204]: lo: Link UP157beta # [7209084.901210] beta systemd-networkd[204]: lo: Gained carrier158beta # [7209084.901376] beta systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.159beta # [7209084.901761] beta systemd[1]: Started Network Management.160beta # [7209084.901935] beta systemd-networkd[204]: eth1: Link UP161beta # [7209084.902232] beta systemd-networkd[204]: eth1: Gained carrier162beta # [7209084.903116] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...163beta # [7209084.962292] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.164alpha # [7209084.906044] alpha systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted165alpha # [7209084.906139] alpha systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted166alpha # [7209084.912718] 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.167alpha # [7209084.912878] 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.168alpha # [7209084.913039] alpha systemd-networkd[204]: lo: Link UP169alpha # [7209084.913042] alpha systemd-networkd[204]: lo: Gained carrier170alpha # [7209084.913254] alpha systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.171alpha # [7209084.913659] alpha systemd[1]: Started Network Management.172alpha # [7209084.952394] alpha systemd-networkd[204]: eth1: Link UP173alpha # [7209084.952471] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...174alpha # [7209084.952664] alpha systemd-networkd[204]: eth1: Gained carrier175alpha # [7209085.010395] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.176beta # [7209085.148217] beta systemd-resolved[110]: Positive Trust Anchors:177beta # [7209085.148234] beta systemd-resolved[110]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d178beta # [7209085.148238] beta systemd-resolved[110]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16179beta # [7209085.148273] beta 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 test180beta # [7209085.171088] beta systemd-resolved[110]: Using system hostname 'beta'.181beta # [7209085.234874] beta systemd[1]: Started Network Name Resolution.182beta # [7209085.234989] beta systemd[1]: Reached target Network.183beta # [7209085.235056] beta systemd[1]: Reached target System Initialization.184beta # [7209085.235108] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container185beta # [7209085.235139] beta systemd[1]: Started Daily Cleanup of Temporary Directories.186beta # [7209085.235156] beta systemd[1]: Reached target Timer Units.187beta # [7209085.235293] beta systemd[1]: Listening on D-Bus System Message Bus Socket.188beta # [7209085.235422] beta systemd[1]: Listening on Nix Daemon Socket.189beta # [7209085.235530] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.190beta # [7209085.235553] beta systemd[1]: Reached target Socket Units.191beta # [7209085.235591] beta systemd[1]: Reached target Basic System.192beta # [7209085.237253] beta systemd[1]: Starting data mesher daemon...193beta # [7209085.238310] beta systemd[1]: Starting Import lastlog data into lastlog2 database...194beta # [7209085.239202] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...195beta # [7209085.240527] beta systemd[1]: Starting D-Bus System Message Bus...196beta # [7209085.300916] beta systemd[1]: Finished Import lastlog data into lastlog2 database.197alpha # [7209085.172410] alpha systemd-resolved[112]: Positive Trust Anchors:198alpha # [7209085.172422] alpha systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d199alpha # [7209085.172427] alpha systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16200alpha # [7209085.172461] 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 test201alpha # [7209085.194840] alpha systemd-resolved[112]: Using system hostname 'alpha'.202alpha # [7209085.237634] alpha systemd[1]: Started Network Name Resolution.203alpha # [7209085.237731] alpha systemd[1]: Reached target Network.204alpha # [7209085.237796] alpha systemd[1]: Reached target System Initialization.205alpha # [7209085.237848] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container206alpha # [7209085.237871] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.207alpha # [7209085.237890] alpha systemd[1]: Reached target Timer Units.208alpha # [7209085.238015] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.209alpha # [7209085.238146] alpha systemd[1]: Listening on Nix Daemon Socket.210alpha # [7209085.238250] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.211alpha # [7209085.238273] alpha systemd[1]: Reached target Socket Units.212alpha # [7209085.238309] alpha systemd[1]: Reached target Basic System.213alpha # [7209085.239513] alpha systemd[1]: Starting data mesher daemon...214alpha # [7209085.240473] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...215alpha # [7209085.241368] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...216alpha # [7209085.284484] alpha systemd[1]: Starting D-Bus System Message Bus...217alpha # [7209085.300863] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.218gamma # [7209085.169742] gamma systemd-resolved[113]: Positive Trust Anchors:219gamma # [7209085.169765] gamma systemd-resolved[113]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d220gamma # [7209085.169770] gamma systemd-resolved[113]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16221gamma # [7209085.169803] gamma systemd-resolved[113]: 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 test222gamma # [7209085.192448] gamma systemd-resolved[113]: Using system hostname 'gamma'.223gamma # [7209085.237505] gamma systemd[1]: Started Network Name Resolution.224gamma # [7209085.237611] gamma systemd[1]: Reached target Network.225gamma # [7209085.237674] gamma systemd[1]: Reached target System Initialization.226gamma # [7209085.237723] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container227gamma # [7209085.237749] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.228gamma # [7209085.237768] gamma systemd[1]: Reached target Timer Units.229gamma # [7209085.237900] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.230gamma # [7209085.238032] gamma systemd[1]: Listening on Nix Daemon Socket.231gamma # [7209085.238143] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.232gamma # [7209085.238162] gamma systemd[1]: Reached target Socket Units.233gamma # [7209085.238198] gamma systemd[1]: Reached target Basic System.234gamma # [7209085.239522] gamma systemd[1]: Starting data mesher daemon...235gamma # [7209085.240415] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...236gamma # [7209085.241338] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...237gamma # [7209085.284483] gamma systemd[1]: Starting D-Bus System Message Bus...238gamma # [7209085.300852] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.239gamma # [7209085.535788] gamma nsncd[212]: Aug 31 09:55:11.588 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"240gamma # [7209085.536372] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).241gamma # [7209085.536447] gamma systemd[1]: Reached target Host and Network Name Lookups.242gamma # [7209085.536514] gamma systemd[1]: Reached target User and Group Name Lookups.243gamma # [7209085.564582] gamma systemd[1]: Starting User Login Management...244gamma # [7209085.565532] gamma systemd[1]: Starting Permit User Sessions...245gamma # [7209085.580261] gamma systemd[1]: Finished Permit User Sessions.246gamma # [7209085.582780] gamma systemd[1]: Started Console Getty.247gamma # [7209085.582865] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0248gamma # [7209085.582885] gamma systemd[1]: Reached target Login Prompts.249alpha # [7209085.517649] alpha nsncd[212]: Aug 31 09:55:11.570 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"250alpha # [7209085.517819] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).251alpha # [7209085.517893] alpha systemd[1]: Reached target Host and Network Name Lookups.252alpha # [7209085.517950] alpha systemd[1]: Reached target User and Group Name Lookups.253alpha # [7209085.519230] alpha systemd[1]: Starting User Login Management...254alpha # [7209085.520244] alpha systemd[1]: Starting Permit User Sessions...255alpha # [7209085.572344] alpha systemd[1]: Finished Permit User Sessions.256alpha # [7209085.573542] alpha systemd[1]: Started Console Getty.257alpha # [7209085.573585] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0258alpha # [7209085.573626] alpha systemd[1]: Reached target Login Prompts.259beta # [7209085.550913] beta nsncd[212]: Aug 31 09:55:11.604 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"260beta # [7209085.551062] beta systemd[1]: Started Name Service Cache Daemon (nsncd).261beta # [7209085.551137] beta systemd[1]: Reached target Host and Network Name Lookups.262beta # [7209085.551196] beta systemd[1]: Reached target User and Group Name Lookups.263beta # [7209085.565692] beta systemd[1]: Starting User Login Management...264beta # [7209085.568239] beta systemd[1]: Starting Permit User Sessions...265beta # [7209085.578099] beta systemd[1]: Finished Permit User Sessions.266beta # [7209085.579946] beta systemd[1]: Started Console Getty.267beta # [7209085.579997] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0268beta # [7209085.580033] beta systemd[1]: Reached target Login Prompts.269alpha # [7209086.112173] alpha systemd-networkd[204]: eth1: Gained IPv6LL270alpha # [7209086.297922] alpha dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...271alpha # [7209086.299180] alpha dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'272alpha # [7209086.299180] alpha dbus-broker-launch[213]: Invalid user-name in /nix/store/cpw251a8nnv83rza5mdsgkafc9msqh5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"273alpha # [7209086.299663] alpha systemd[1]: Started D-Bus System Message Bus.274alpha # [7209086.307381] alpha dbus-broker-launch[213]: Ready275beta # [7209086.191449] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...276beta # [7209086.193525] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'277beta # [7209086.193525] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/cpw251a8nnv83rza5mdsgkafc9msqh5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"278beta # [7209086.194063] beta systemd[1]: Started D-Bus System Message Bus.279beta # [7209086.201216] beta dbus-broker-launch[213]: Ready280beta # [7209086.244143] beta systemd-networkd[204]: eth1: Gained IPv6LL281gamma # [7209086.253881] gamma dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...282gamma # [7209086.254696] gamma dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'283gamma # [7209086.254696] gamma dbus-broker-launch[213]: Invalid user-name in /nix/store/cpw251a8nnv83rza5mdsgkafc9msqh5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"284gamma # [7209086.255125] gamma systemd[1]: Started D-Bus System Message Bus.285gamma # [7209086.262104] gamma dbus-broker-launch[213]: Ready286gamma # [7209086.720156] gamma systemd-networkd[204]: eth1: Gained IPv6LL287gamma # [7209086.806814] gamma data-mesher[210]: time=2026-08-31T09:55:12.859Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]288gamma # [7209086.807914] gamma data-mesher[210]: time=2026-08-31T09:55:12.861Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g: [/dns/alpha.clan/tcp/7946]} {12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r289gamma # [7209086.807960] gamma data-mesher[210]: time=2026-08-31T09:55:12.861Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml290beta # [7209086.781051] beta data-mesher[210]: time=2026-08-31T09:55:12.834Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]291beta # [7209086.782165] beta data-mesher[210]: time=2026-08-31T09:55:12.835Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g: [/dns/alpha.clan/tcp/7946]} {12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498292beta # [7209086.782209] beta data-mesher[210]: time=2026-08-31T09:55:12.835Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml293beta # [7209086.980084] beta data-mesher[210]: time=2026-08-31T09:55:13.033Z level=INFO msg="checking file integrity"294beta # [7209086.980243] beta data-mesher[210]: time=2026-08-31T09:55:13.033Z level=INFO msg="file integrity check complete"295beta # [7209086.984582] beta data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="libp2p host created" peer_id=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 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]"296beta # [7209086.984658] beta data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name297beta # [7209086.984658] beta data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="registered HTTP route" method=GET path=/files298beta # [7209086.984658] beta data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name299beta # [7209086.984658] beta data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="starting server"300beta # [7209086.984763] beta data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="waiting for DHT to populate" delay=10s301beta # [7209086.984818] beta data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="HTTP server listening" address=[::1]:7331302beta # [7209086.984852] beta data-mesher[210]: time=2026-08-31T09:55:13.038Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331303beta # [7209086.991910] beta systemd-logind[229]: New seat seat0.304beta # [7209086.992164] beta systemd[1]: Started User Login Management.305beta # [7209086.993532] beta data-mesher[210]: time=2026-08-31T09:55:13.046Z level=INFO msg="peer connected" peer_id=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r remote_addr=/ip6/2001:db8:1::3/tcp/7946306beta # [7209086.993525] beta systemd[1]: Starting linger-users.service...307beta # [7209086.994517] beta data-mesher[210]: time=2026-08-31T09:55:13.047Z level=INFO msg="peer connected" peer_id=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g remote_addr=/ip4/192.168.1.1/tcp/7946308beta # [7209087.011123] beta systemd[1]: linger-users.service: Deactivated successfully.309beta # [7209087.011189] beta systemd[1]: Finished linger-users.service.310alpha # [7209086.791284] alpha data-mesher[210]: time=2026-08-31T09:55:12.844Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]311alpha # [7209086.801593] alpha data-mesher[210]: time=2026-08-31T09:55:12.845Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g: [/dns/alpha.clan/tcp/7946]} {12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g312alpha # [7209086.801593] alpha data-mesher[210]: time=2026-08-31T09:55:12.845Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml313alpha # [7209086.972393] alpha systemd-logind[229]: New seat seat0.314alpha # [7209086.972599] alpha systemd[1]: Started User Login Management.315alpha # [7209086.974862] alpha systemd[1]: Starting linger-users.service...316alpha # [7209086.983719] alpha data-mesher[210]: time=2026-08-31T09:55:13.036Z level=INFO msg="checking file integrity"317alpha # [7209086.983856] alpha data-mesher[210]: time=2026-08-31T09:55:13.037Z level=INFO msg="file integrity check complete"318alpha # [7209086.988251] alpha systemd[1]: linger-users.service: Deactivated successfully.319alpha # [7209086.988474] alpha systemd[1]: Finished linger-users.service.320alpha # [7209086.988628] alpha data-mesher[210]: time=2026-08-31T09:55:13.041Z level=INFO msg="libp2p host created" peer_id=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g 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]"321alpha # [7209086.988676] alpha data-mesher[210]: time=2026-08-31T09:55:13.041Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name322alpha # [7209086.988676] alpha data-mesher[210]: time=2026-08-31T09:55:13.041Z level=INFO msg="registered HTTP route" method=GET path=/files323alpha # [7209086.988676] alpha data-mesher[210]: time=2026-08-31T09:55:13.041Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name324alpha # [7209086.988676] alpha data-mesher[210]: time=2026-08-31T09:55:13.041Z level=INFO msg="starting server"325alpha # [7209086.988795] alpha data-mesher[210]: time=2026-08-31T09:55:13.041Z level=INFO msg="waiting for DHT to populate" delay=10s326alpha # [7209086.988998] alpha data-mesher[210]: time=2026-08-31T09:55:13.042Z level=INFO msg="HTTP server listening" address=[::1]:7331327alpha # [7209086.989316] alpha data-mesher[210]: time=2026-08-31T09:55:13.042Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331328alpha # [7209086.993879] alpha data-mesher[210]: time=2026-08-31T09:55:13.046Z level=INFO msg="peer connected" peer_id=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 remote_addr=/ip4/192.168.1.2/tcp/7946329alpha # [7209086.999101] alpha data-mesher[210]: time=2026-08-31T09:55:13.051Z level=INFO msg="peer connected" peer_id=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r remote_addr=/ip4/192.168.1.3/tcp/7946330alpha # [7209087.001294] alpha data-mesher[210]: time=2026-08-31T09:55:13.054Z level=INFO msg="peer connected" peer_id=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r remote_addr=/ip6/2001:db8:1::3/tcp/7946331gamma # [7209086.982453] gamma data-mesher[210]: time=2026-08-31T09:55:13.035Z level=INFO msg="checking file integrity"332gamma # [7209086.982730] gamma data-mesher[210]: time=2026-08-31T09:55:13.035Z level=INFO msg="file integrity check complete"333gamma # [7209086.986912] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="libp2p host created" peer_id=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r 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]"334gamma # [7209086.988084] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="registered HTTP route" method=GET path=/files335gamma # [7209086.988084] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name336gamma # [7209086.988084] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name337gamma # [7209086.988084] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="starting server"338gamma # [7209086.988084] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="waiting for DHT to populate" delay=10s339gamma # [7209086.988084] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="HTTP server listening" address=[::1]:7331340gamma # [7209086.988084] gamma data-mesher[210]: time=2026-08-31T09:55:13.040Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331341gamma # [7209086.991911] gamma systemd-logind[229]: New seat seat0.342gamma # [7209086.992220] gamma systemd[1]: Started User Login Management.343gamma # [7209086.992633] gamma data-mesher[210]: time=2026-08-31T09:55:13.045Z level=INFO msg="peer connected" peer_id=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 remote_addr=/ip6/2001:db8:1::2/tcp/7946344gamma # [7209086.993492] gamma systemd[1]: Starting linger-users.service...345gamma # [7209086.999056] gamma data-mesher[210]: time=2026-08-31T09:55:13.051Z level=INFO msg="peer connected" peer_id=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g remote_addr=/ip4/192.168.1.1/tcp/7946346gamma # [7209087.002252] gamma data-mesher[210]: time=2026-08-31T09:55:13.055Z level=INFO msg="peer connected" peer_id=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g remote_addr=/ip6/2001:db8:1::1/tcp/46642347gamma # [7209087.006427] gamma systemd[1]: linger-users.service: Deactivated successfully.348gamma # [7209087.006559] gamma systemd[1]: Finished linger-users.service.349alpha: still waiting for container 'alpha' to reach ready state...350gamma # [7209096.985876] gamma data-mesher[210]: time=2026-08-31T09:55:23.039Z level=INFO msg="received state sync from peer" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498351gamma # [7209096.985876] gamma data-mesher[210]: time=2026-08-31T09:55:23.039Z level=INFO msg="merging remote state" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498352gamma # [7209096.987580] gamma data-mesher[210]: time=2026-08-31T09:55:23.040Z level=INFO msg="performing state exchange with peers on join" count=1353gamma # [7209096.987628] gamma data-mesher[210]: time=2026-08-31T09:55:23.040Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 timeout=5s354gamma # [7209096.988282] gamma data-mesher[210]: time=2026-08-31T09:55:23.041Z level=INFO msg="merging remote state" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498355gamma # [7209096.988282] gamma data-mesher[210]: time=2026-08-31T09:55:23.041Z level=INFO msg="state exchange complete" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 timeout=5s356gamma # [7209096.988357] gamma data-mesher[210]: time=2026-08-31T09:55:23.041Z level=INFO msg="server started"357gamma # [7209096.988428] gamma data-mesher[210]: time=2026-08-31T09:55:23.041Z level=INFO msg="starting expired-file sweeper" interval=1m0s358gamma # [7209096.988652] gamma systemd[1]: Started data mesher daemon.359gamma # [7209096.989175] gamma systemd[1]: Reached target Multi-User System.360gamma # [7209096.989606] gamma systemd[1]: Startup finished in 13.569s.361gamma # [7209096.989675] gamma data-mesher[210]: time=2026-08-31T09:55:23.042Z level=INFO msg="received state sync from peer" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g362gamma # [7209096.989675] gamma data-mesher[210]: time=2026-08-31T09:55:23.042Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g363beta # [7209096.985352] beta data-mesher[210]: time=2026-08-31T09:55:23.038Z level=INFO msg="performing state exchange with peers on join" count=1364beta # [7209096.985352] beta data-mesher[210]: time=2026-08-31T09:55:23.038Z level=DEBUG msg="initiating state exchange" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s365beta # [7209096.986083] beta data-mesher[210]: time=2026-08-31T09:55:23.039Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r366beta # [7209096.986083] beta data-mesher[210]: time=2026-08-31T09:55:23.039Z level=INFO msg="state exchange complete" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s367beta # [7209096.986145] beta data-mesher[210]: time=2026-08-31T09:55:23.039Z level=INFO msg="server started"368beta # [7209096.986348] beta systemd[1]: Started data mesher daemon.369beta # [7209096.986592] beta data-mesher[210]: time=2026-08-31T09:55:23.039Z level=INFO msg="starting expired-file sweeper" interval=1m0s370beta # [7209096.986592] beta systemd[1]: Reached target Multi-User System.371beta # [7209096.986783] beta systemd[1]: Startup finished in 13.533s.372beta # [7209096.988138] beta data-mesher[210]: time=2026-08-31T09:55:23.041Z level=INFO msg="received state sync from peer" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r373beta # [7209096.988138] beta data-mesher[210]: time=2026-08-31T09:55:23.041Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r374alpha # [7209096.989288] alpha data-mesher[210]: time=2026-08-31T09:55:23.042Z level=INFO msg="performing state exchange with peers on join" count=1375alpha # [7209096.989652] alpha data-mesher[210]: time=2026-08-31T09:55:23.042Z level=DEBUG msg="initiating state exchange" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s376alpha # [7209096.990020] alpha data-mesher[210]: time=2026-08-31T09:55:23.043Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r377alpha # [7209096.990020] alpha data-mesher[210]: time=2026-08-31T09:55:23.043Z level=INFO msg="state exchange complete" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s378alpha # [7209096.990089] alpha data-mesher[210]: time=2026-08-31T09:55:23.043Z level=INFO msg="server started"379alpha # [7209096.990163] alpha data-mesher[210]: time=2026-08-31T09:55:23.043Z level=INFO msg="starting expired-file sweeper" interval=1m0s380alpha # [7209096.990290] alpha systemd[1]: Started data mesher daemon.381alpha # [7209096.990526] alpha systemd[1]: Reached target Multi-User System.382alpha # [7209096.990681] alpha systemd[1]: Startup finished in 13.636s.383alpha: (finished: waiting for unit data-mesher.service, in 14.68 seconds)384beta: waiting for unit data-mesher.service385beta: (finished: waiting for unit data-mesher.service, in 0.01 seconds)386gamma: waiting for unit data-mesher.service387gamma: (finished: waiting for unit data-mesher.service, in 0.01 seconds)388alpha: must succeed: echo -n 'hello world' > /tmp/test_file389alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)390alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key391alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)392??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.393 File "/nix/store/5ji70zizd2wdp8kz2n2hzzcabdw71fwn-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39394alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file395??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.396 File "/nix/store/5ji70zizd2wdp8kz2n2hzzcabdw71fwn-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39397alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)398beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file399alpha # [7209097.550203] alpha data-mesher[210]: time=2026-08-31T09:55:23.603Z level=INFO msg=http_request uri=/files/test_file status=204400gamma # [7209101.989335] gamma data-mesher[210]: time=2026-08-31T09:55:28.042Z level=DEBUG msg="attempting push/pull" peer_count=2401gamma # [7209101.989722] gamma data-mesher[210]: time=2026-08-31T09:55:28.042Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s402gamma # [7209101.990504] gamma data-mesher[210]: time=2026-08-31T09:55:28.043Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g403gamma # [7209101.990541] gamma data-mesher[210]: time=2026-08-31T09:55:28.043Z level=DEBUG msg="new file detected" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g name=test_file name=test_file404gamma # [7209101.990563] gamma data-mesher[210]: time=2026-08-31T09:55:28.043Z level=INFO msg="state exchange complete" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s405gamma # [7209101.990619] gamma data-mesher[210]: time=2026-08-31T09:55:28.043Z level=INFO msg="scheduling file download" name=test_file406gamma # [7209101.990659] gamma data-mesher[210]: time=2026-08-31T09:55:28.043Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-31 09:55:23.601 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g407gamma # [7209101.990687] gamma data-mesher[210]: time=2026-08-31T09:55:28.043Z level=DEBUG msg="push/pull successful" interval=5s408gamma # [7209101.996123] gamma data-mesher[210]: time=2026-08-31T09:55:28.049Z level=INFO msg="download complete" name=test_file signed_at="2026-08-31 09:55:23.601 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g written=true elapsed=5.454316ms409beta # [7209101.988143] beta data-mesher[210]: time=2026-08-31T09:55:28.041Z level=DEBUG msg="attempting push/pull" peer_count=2410beta # [7209101.988143] beta data-mesher[210]: time=2026-08-31T09:55:28.041Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s411alpha # [7209101.988750] alpha data-mesher[210]: time=2026-08-31T09:55:28.041Z level=INFO msg="received state sync from peer" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498412alpha # [7209101.988750] alpha data-mesher[210]: time=2026-08-31T09:55:28.041Z level=INFO msg="merging remote state" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498413alpha # [7209101.990054] alpha data-mesher[210]: time=2026-08-31T09:55:28.043Z level=INFO msg="received state sync from peer" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r414alpha # [7209101.990054] alpha data-mesher[210]: time=2026-08-31T09:55:28.043Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r415alpha # [7209101.990256] alpha data-mesher[210]: time=2026-08-31T09:55:28.043Z level=INFO msg="received file request" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_file416alpha # [7209101.990320] alpha data-mesher[210]: time=2026-08-31T09:55:28.043Z level=DEBUG msg="attempting push/pull" peer_count=2417alpha # [7209101.990379] alpha data-mesher[210]: time=2026-08-31T09:55:28.043Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 timeout=5s418alpha # [7209101.992447] alpha data-mesher[210]: time=2026-08-31T09:55:28.045Z level=INFO msg="received file request" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_file419alpha # [7209101.993508] alpha data-mesher[210]: time=2026-08-31T09:55:28.046Z level=INFO msg="file transfer complete" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_file420alpha # [7209101.993603] alpha data-mesher[210]: time=2026-08-31T09:55:28.046Z level=INFO msg="merging remote state" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498421alpha # [7209101.993603] alpha data-mesher[210]: time=2026-08-31T09:55:28.046Z level=INFO msg="state exchange complete" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 timeout=5s422alpha # [7209101.993727] alpha data-mesher[210]: time=2026-08-31T09:55:28.046Z level=DEBUG msg="push/pull successful" interval=5s423alpha # [7209101.993814] alpha data-mesher[210]: time=2026-08-31T09:55:28.046Z level=INFO msg="file transfer complete" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_file424beta # [7209101.989430] beta data-mesher[210]: time=2026-08-31T09:55:28.042Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g425beta # [7209101.989509] beta data-mesher[210]: time=2026-08-31T09:55:28.042Z level=DEBUG msg="new file detected" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g name=test_file name=test_file426beta # [7209101.989509] beta data-mesher[210]: time=2026-08-31T09:55:28.042Z level=INFO msg="state exchange complete" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s427beta # [7209101.989626] beta data-mesher[210]: time=2026-08-31T09:55:28.042Z level=INFO msg="scheduling file download" name=test_file428beta # [7209101.989626] beta data-mesher[210]: time=2026-08-31T09:55:28.042Z level=DEBUG msg="push/pull successful" interval=5s429beta # [7209101.989626] beta data-mesher[210]: time=2026-08-31T09:55:28.042Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-31 09:55:23.601 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g430beta # [7209101.992966] beta data-mesher[210]: time=2026-08-31T09:55:28.046Z level=INFO msg="received state sync from peer" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g431beta # [7209101.993039] beta data-mesher[210]: time=2026-08-31T09:55:28.046Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g432beta # [7209101.993039] beta data-mesher[210]: time=2026-08-31T09:55:28.046Z level=DEBUG msg="new file detected" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g name=test_file name=test_file433beta # [7209101.996370] beta data-mesher[210]: time=2026-08-31T09:55:28.049Z level=INFO msg="download complete" name=test_file signed_at="2026-08-31 09:55:23.601 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g written=true elapsed=6.789455ms434beta: (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)435gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file436gamma: (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)437beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key438beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)439alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file440beta # [7209102.650417] beta data-mesher[210]: time=2026-08-31T09:55:28.703Z level=INFO msg=http_request uri=/files/test_file status=204441gamma # [7209106.991622] gamma data-mesher[210]: time=2026-08-31T09:55:33.044Z level=DEBUG msg="attempting push/pull" peer_count=2442gamma # [7209106.991979] gamma data-mesher[210]: time=2026-08-31T09:55:33.044Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 timeout=5s443gamma # [7209106.992556] gamma data-mesher[210]: time=2026-08-31T09:55:33.045Z level=INFO msg="merging remote state" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498444gamma # [7209106.994421] gamma data-mesher[210]: time=2026-08-31T09:55:33.047Z level=DEBUG msg="imported tombstone" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 name=test_file written=true445gamma # [7209106.994485] gamma data-mesher[210]: time=2026-08-31T09:55:33.047Z level=INFO msg="state exchange complete" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 timeout=5s446gamma # [7209106.994485] gamma data-mesher[210]: time=2026-08-31T09:55:33.047Z level=INFO msg="received state sync from peer" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g447gamma # [7209106.994485] gamma data-mesher[210]: time=2026-08-31T09:55:33.047Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g448gamma # [7209106.994551] gamma data-mesher[210]: time=2026-08-31T09:55:33.047Z level=DEBUG msg="push/pull successful" interval=5s449alpha # [7209106.990519] alpha data-mesher[210]: time=2026-08-31T09:55:33.043Z level=INFO msg="received state sync from peer" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498450alpha # [7209106.990519] alpha data-mesher[210]: time=2026-08-31T09:55:33.043Z level=INFO msg="merging remote state" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498451alpha # [7209106.993279] alpha data-mesher[210]: time=2026-08-31T09:55:33.046Z level=DEBUG msg="imported tombstone" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 name=test_file written=true452alpha # [7209106.994111] alpha data-mesher[210]: time=2026-08-31T09:55:33.047Z level=DEBUG msg="attempting push/pull" peer_count=2453alpha # [7209106.994111] alpha data-mesher[210]: time=2026-08-31T09:55:33.047Z level=DEBUG msg="initiating state exchange" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s454alpha # [7209106.994794] alpha data-mesher[210]: time=2026-08-31T09:55:33.047Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r455alpha # [7209106.994886] alpha data-mesher[210]: time=2026-08-31T09:55:33.047Z level=INFO msg="state exchange complete" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s456alpha # [7209106.994886] alpha data-mesher[210]: time=2026-08-31T09:55:33.048Z level=DEBUG msg="push/pull successful" interval=5s457beta # [7209106.989689] beta data-mesher[210]: time=2026-08-31T09:55:33.042Z level=DEBUG msg="attempting push/pull" peer_count=2458beta # [7209106.990077] beta data-mesher[210]: time=2026-08-31T09:55:33.042Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s459beta # [7209106.992360] beta data-mesher[210]: time=2026-08-31T09:55:33.045Z level=INFO msg="received state sync from peer" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r460beta # [7209106.992360] beta data-mesher[210]: time=2026-08-31T09:55:33.045Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r461beta # [7209106.993636] beta data-mesher[210]: time=2026-08-31T09:55:33.046Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g462beta # [7209106.993819] beta data-mesher[210]: time=2026-08-31T09:55:33.046Z level=INFO msg="state exchange complete" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s463beta # [7209106.993819] beta data-mesher[210]: time=2026-08-31T09:55:33.046Z level=DEBUG msg="push/pull successful" interval=5s464alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)465beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file466beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)467gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file468gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)469alpha: must succeed: cat /nix/store/sqi97m2l87iwib6dv6y6p010s37vklzv-per-machine-alpha-data-mesher-node-identity_identity.pub470alpha: (finished: must succeed: cat /nix/store/sqi97m2l87iwib6dv6y6p010s37vklzv-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)471alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file472alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)473alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert474alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)475alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s476alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s, in 0.01 seconds)477beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s478alpha # [7209107.756115] alpha data-mesher[210]: time=2026-08-31T09:55:33.809Z level=INFO msg=http_request uri=/files/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s status=204479gamma # [7209111.995512] gamma data-mesher[210]: time=2026-08-31T09:55:38.048Z level=DEBUG msg="attempting push/pull" peer_count=2480gamma # [7209111.995512] gamma data-mesher[210]: time=2026-08-31T09:55:38.048Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s481gamma # [7209111.995512] gamma data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="received state sync from peer" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g482gamma # [7209111.995512] gamma data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g483gamma # [7209111.995972] gamma data-mesher[210]: time=2026-08-31T09:55:38.048Z level=DEBUG msg="new file detected" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g name=test_file name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s484gamma # [7209111.995972] gamma data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="scheduling file download" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s485gamma # [7209111.995972] gamma data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="downloading file" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s signed_at="2026-08-31 09:55:33.806 +0000 UTC" signed_by="sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g486gamma # [7209111.996243] gamma data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g487gamma # [7209111.996730] gamma data-mesher[210]: time=2026-08-31T09:55:38.049Z level=DEBUG msg="new file detected" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g name=test_file name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s488gamma # [7209111.996782] gamma data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="state exchange complete" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s489gamma # [7209111.996805] gamma data-mesher[210]: time=2026-08-31T09:55:38.049Z level=DEBUG msg="push/pull successful" interval=5s490gamma # [7209111.998396] gamma data-mesher[210]: time=2026-08-31T09:55:38.051Z level=INFO msg="download complete" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s signed_at="2026-08-31 09:55:33.806 +0000 UTC" signed_by="sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g written=true elapsed=2.613356ms491alpha # [7209111.994937] alpha data-mesher[210]: time=2026-08-31T09:55:38.048Z level=DEBUG msg="attempting push/pull" peer_count=2492alpha # [7209111.994937] alpha data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="received state sync from peer" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498493alpha # [7209111.995338] alpha data-mesher[210]: time=2026-08-31T09:55:38.048Z level=DEBUG msg="initiating state exchange" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s494alpha # [7209111.995338] alpha data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="merging remote state" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498495alpha # [7209111.995646] alpha data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="received state sync from peer" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r496alpha # [7209111.995646] alpha data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r497alpha # [7209111.995847] alpha data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="merging remote state" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r498alpha # [7209111.995916] alpha data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="state exchange complete" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r timeout=5s499alpha # [7209111.995916] alpha data-mesher[210]: time=2026-08-31T09:55:38.049Z level=DEBUG msg="push/pull successful" interval=5s500alpha # [7209111.996087] alpha data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="received file request" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s501alpha # [7209111.996360] alpha data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="file transfer complete" peer=12D3KooWP9dVC44FsZsB8XvQQmksu88gdTFtJDeSJXtcwGxhGX1r network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s502alpha # [7209111.997181] alpha data-mesher[210]: time=2026-08-31T09:55:38.050Z level=INFO msg="received file request" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s503alpha # [7209111.997612] alpha data-mesher[210]: time=2026-08-31T09:55:38.050Z level=INFO msg="file transfer complete" peer=12D3KooWJPgbQSHBJXzprM8vMGqzzZUZVUdz3QrrmSvNQbhhw498 network="LM5F3CCzT2YND1DwDVftwpbkgEhwBAj72G9m07r/w0M=" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s504beta # [7209111.994398] beta data-mesher[210]: time=2026-08-31T09:55:38.047Z level=DEBUG msg="attempting push/pull" peer_count=2505beta # [7209111.995144] beta data-mesher[210]: time=2026-08-31T09:55:38.047Z level=DEBUG msg="initiating state exchange" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s506beta # [7209111.995381] beta data-mesher[210]: time=2026-08-31T09:55:38.048Z level=INFO msg="merging remote state" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g507beta # [7209111.995995] beta data-mesher[210]: time=2026-08-31T09:55:38.049Z level=DEBUG msg="new file detected" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g name=test_file name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s508beta # [7209111.996097] beta data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="state exchange complete" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g timeout=5s509beta # [7209111.996097] beta data-mesher[210]: time=2026-08-31T09:55:38.049Z level=DEBUG msg="push/pull successful" interval=5s510beta # [7209111.996196] beta data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="scheduling file download" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s511beta # [7209111.996277] beta data-mesher[210]: time=2026-08-31T09:55:38.049Z level=INFO msg="downloading file" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s signed_at="2026-08-31 09:55:33.806 +0000 UTC" signed_by="sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g512beta # [7209111.999181] beta data-mesher[210]: time=2026-08-31T09:55:38.052Z level=INFO msg="download complete" name=test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s signed_at="2026-08-31 09:55:33.806 +0000 UTC" signed_by="sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s=" peer=12D3KooWMkDrY3UBbiEF3ZL4kTar9ZxEh2SSpsWtCoLEBBoYYX3g written=true elapsed=2.8948ms513beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s, in 5.04 seconds)514gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s515gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s, in 0.01 seconds)516alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert517alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)518alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub519Error: failed to update file: 403 Forbidden, signer sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s= is not authorized for this file test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s520alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/32vjikavq9qsb0q34yz8gx86543d6j39-shared-data-mesher-network_network.pub, in 0.03 seconds)521(finished: run the VM test script, in 30.05 seconds)522test script finished in 30.10s523cleanup524kill NspawnMachine (pid 59)525alpha # [7209112.850592] alpha data-mesher[210]: time=2026-08-31T09:55:38.903Z level=INFO msg=http_request uri=/files/test_ns/sTyE74kWzJY4LfEoHmpsNPxlsxmELEVWAjkiBP50T9s status=403526alpha # [7209112.899861] alpha systemd-networkd[204]: eth1: Link DOWN527alpha # [7209112.899876] alpha systemd-networkd[204]: eth1: Lost carrier528alpha # [7209113.120879] alpha systemd-networkd[204]: eth1: Lost IPv6LL address fe80::7882:45ff:fef4:cc3b.529kill NspawnMachine (pid 53)530kill NspawnMachine (pid 54)531Container alpha terminated by signal KILL.532Container beta terminated by signal KILL.533Container gamma terminated by signal KILL.534(finished: cleanup, in 0.79 seconds)