container-test-run-data-mesher log

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

1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 alpha, beta, gamma,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12alpha: systemd-nspawn running (pid 52)13alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...14gamma: systemd-nspawn running (pid 56)15beta: systemd-nspawn running (pid 54)16gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...17beta: Waiting for journal at /build/vm-state-beta/var/log/journal...18(finished: start all VMs, in 0.00 seconds)19alpha: waiting for unit data-mesher.service20nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE21nixos-nspawn(alpha): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.22nixos-nspawn(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(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 gamma on /build/vm-state-gamma.30░ Spawning container alpha on /build/vm-state-alpha.31░ Spawning container beta on /build/vm-state-beta.32alpha # No journal boot entry found for the specified boot (+0).33gamma # No journal files were found.34gamma # No journal boot entry found for the specified boot (+0).35beta # No journal boot entry found for the specified boot (+0).36alpha # [335632.281470] alpha systemd-journald[87]: Journal started37alpha # [335632.281533] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/08f7443893a740019000b359b36a7907) is 8M, max 2.5G, 2.4G free.38alpha # [335632.287933] alpha systemd[1]: Starting Flush Journal to Persistent Storage...39alpha # [335632.289067] alpha systemd[1]: Starting Network Name Resolution...40alpha # [335632.289789] alpha systemd[1]: Starting Create Static Device Nodes in /dev...41gamma # [335632.301622] gamma systemd-journald[87]: Journal started42alpha # [335632.298618] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/08f7443893a740019000b359b36a7907 is 1.657ms for 5 entries.43alpha # [335632.298618] alpha systemd-journald[87]: System Journal (/var/log/journal/08f7443893a740019000b359b36a7907) is 8M, max 4G, 3.9G free.44alpha # [335632.305036] alpha systemd[1]: Finished Create Static Device Nodes in /dev.45alpha # [335632.305290] alpha systemd[1]: Reached target Preparation for Local File Systems.46alpha # [335632.305376] alpha systemd[1]: Reached target Local File Systems.47alpha # [335632.306121] alpha systemd[1]: Listening on Boot Loader Control Service Socket.48alpha # [335632.306169] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container49alpha # [335632.307029] alpha systemd[1]: Starting Save Transient machine-id to Disk...50alpha # [335632.307069] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys51alpha # [335632.356829] alpha systemd[1]: Finished Flush Journal to Persistent Storage.52alpha # [335632.358380] alpha systemd[1]: Starting Create System Files and Directories...53alpha # [335632.376094] alpha systemd-tmpfiles[150]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted54alpha # [335632.376697] alpha systemd-tmpfiles[150]: fchmod() of /var/log/journal failed: Operation not permitted55alpha # [335632.376825] alpha systemd-tmpfiles[150]: fchmod() of /var/log/journal/08f7443893a740019000b359b36a7907 failed: Operation not permitted56alpha # [335632.377018] alpha systemd-tmpfiles[150]: fchmod() of /run/log/journal failed: Operation not permitted57alpha # [335632.378861] alpha systemd[1]: Finished Create System Files and Directories.58alpha # [335632.379974] alpha systemd[1]: Starting Rebuild Journal Catalog...59alpha # [335632.380707] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...60alpha # [335632.394960] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.61alpha # [335632.402260] alpha systemd[1]: Finished Rebuild Journal Catalog.62alpha # [335632.403449] alpha systemd[1]: Starting Update is Completed...63alpha # [335632.413451] alpha systemd[1]: Finished Update is Completed.64alpha # [335632.446131] alpha systemd[1]: Finished Firewall.65alpha # [335632.446287] alpha systemd[1]: Reached target Preparation for Network.66alpha # [335632.446532] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.67alpha # [335632.447588] alpha systemd[1]: Starting Network Management...68alpha # [335632.500457] alpha systemd[1]: Finished Save Transient machine-id to Disk.69alpha # [335632.830262] alpha systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted70alpha # [335632.830346] alpha systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted71alpha # [335632.837543] 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.72alpha # [335632.837711] 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.73alpha # [335632.837862] alpha systemd-networkd[204]: lo: Link UP74alpha # [335632.837866] alpha systemd-networkd[204]: lo: Gained carrier75alpha # [335632.838032] alpha systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.76alpha # [335632.838486] alpha systemd[1]: Started Network Management.77alpha # [335632.860315] alpha systemd-networkd[204]: eth1: Link UP78alpha # [335632.860757] alpha systemd-networkd[204]: eth1: Gained carrier79gamma # [335632.301690] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/647efea3fcde40d795ddb776bb0a3d75) is 8M, max 2.5G, 2.4G free.80gamma # [335632.303525] gamma systemd[1]: Starting Flush Journal to Persistent Storage...81gamma # [335632.312515] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/647efea3fcde40d795ddb776bb0a3d75 is 2.014ms for 3 entries.82gamma # [335632.312515] gamma systemd-journald[87]: System Journal (/var/log/journal/647efea3fcde40d795ddb776bb0a3d75) is 8M, max 4G, 3.9G free.83gamma # [335632.317389] gamma systemd[1]: Finished Create Static Device Nodes in /dev.84gamma # [335632.324219] gamma systemd[1]: Reached target Preparation for Local File Systems.85gamma # [335632.324313] gamma systemd[1]: Reached target Local File Systems.86gamma # [335632.325162] gamma systemd[1]: Listening on Boot Loader Control Service Socket.87gamma # [335632.325206] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container88gamma # [335632.325968] gamma systemd[1]: Starting Save Transient machine-id to Disk...89gamma # [335632.326001] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys90gamma # [335632.357725] gamma systemd[1]: Finished Flush Journal to Persistent Storage.91gamma # [335632.359181] gamma systemd[1]: Starting Create System Files and Directories...92gamma # [335632.373860] gamma systemd-tmpfiles[139]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted93gamma # [335632.374041] gamma systemd-tmpfiles[139]: fchmod() of /var/log/journal failed: Operation not permitted94gamma # [335632.374167] gamma systemd-tmpfiles[139]: fchmod() of /var/log/journal/647efea3fcde40d795ddb776bb0a3d75 failed: Operation not permitted95gamma # [335632.374356] gamma systemd-tmpfiles[139]: fchmod() of /run/log/journal failed: Operation not permitted96gamma # [335632.375874] gamma systemd[1]: Finished Create System Files and Directories.97gamma # [335632.376866] gamma systemd[1]: Starting Rebuild Journal Catalog...98gamma # [335632.377527] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...99gamma # [335632.390794] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.100gamma # [335632.395695] gamma systemd[1]: Finished Rebuild Journal Catalog.101gamma # [335632.397315] gamma systemd[1]: Starting Update is Completed...102gamma # [335632.407185] gamma systemd[1]: Finished Update is Completed.103gamma # [335632.500424] gamma systemd[1]: Finished Firewall.104gamma # [335632.500617] gamma systemd[1]: Finished Save Transient machine-id to Disk.105gamma # [335632.501174] gamma systemd[1]: Reached target Preparation for Network.106gamma # [335632.501456] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.107gamma # [335632.502516] gamma systemd[1]: Starting Network Management...108gamma # [335632.857245] gamma systemd-networkd[203]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted109gamma # [335632.857329] gamma systemd-networkd[203]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted110gamma # [335632.863467] gamma systemd-networkd[203]: /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.111gamma # [335632.863627] gamma systemd-networkd[203]: /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.112gamma # [335632.863764] gamma systemd-networkd[203]: lo: Link UP113gamma # [335632.863768] gamma systemd-networkd[203]: lo: Gained carrier114gamma # [335632.863941] gamma systemd-networkd[203]: eth1: Configuring with /etc/systemd/network/40-eth1.network.115gamma # [335632.864398] gamma systemd[1]: Started Network Management.116gamma # [335632.864537] gamma systemd-networkd[203]: eth1: Link UP117gamma # [335632.864758] gamma systemd-networkd[203]: eth1: Gained carrier118gamma # [335632.865471] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...119gamma # [335632.889913] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.120gamma # [335632.894850] gamma systemd-resolved[89]: Positive Trust Anchors:121gamma # [335632.894862] gamma systemd-resolved[89]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d122gamma # [335632.894865] gamma systemd-resolved[89]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16123gamma # [335632.894901] gamma systemd-resolved[89]: 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 test124gamma # [335632.916602] gamma systemd-resolved[89]: Using system hostname 'gamma'.125gamma # [335632.918004] gamma systemd[1]: Started Network Name Resolution.126gamma # [335632.918154] gamma systemd[1]: Reached target Network.127gamma # [335632.918272] gamma systemd[1]: Reached target System Initialization.128gamma # [335632.918379] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container129gamma # [335632.918438] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.130gamma # [335632.918480] gamma systemd[1]: Reached target Timer Units.131gamma # [335632.918731] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.132gamma # [335632.918965] gamma systemd[1]: Listening on Nix Daemon Socket.133gamma # [335632.919189] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.134gamma # [335632.919236] gamma systemd[1]: Reached target Socket Units.135gamma # [335632.919315] gamma systemd[1]: Reached target Basic System.136gamma # [335632.921581] gamma systemd[1]: Starting data mesher daemon...137gamma # [335632.922798] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...138gamma # [335632.924309] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...139gamma # [335632.926358] gamma systemd[1]: Starting D-Bus System Message Bus...140gamma # [335632.950506] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.141gamma # [335633.052977] gamma nsncd[210]: Sep 08 04:40:53.038 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"142gamma # [335633.052917] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).143gamma # [335633.053017] gamma systemd[1]: Reached target Host and Network Name Lookups.144gamma # [335633.053125] gamma systemd[1]: Reached target User and Group Name Lookups.145gamma # [335633.055213] gamma systemd[1]: Starting User Login Management...146gamma # [335633.056487] gamma systemd[1]: Starting Permit User Sessions...147gamma # [335633.068541] gamma systemd[1]: Finished Permit User Sessions.148gamma # [335633.069639] gamma systemd[1]: Started Console Getty.149gamma # [335633.069686] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0150gamma # [335633.069712] gamma systemd[1]: Reached target Login Prompts.151gamma # [335633.183529] gamma dbus-broker-launch[211]: Looking up NSS user entry for 'systemd-timesync'...152gamma # [335633.184370] gamma dbus-broker-launch[211]: NSS returned no entry for 'systemd-timesync'153gamma # [335633.184370] gamma dbus-broker-launch[211]: Invalid user-name in /nix/store/y1xvnyfk8grlcjwhifz48bs8cwjiagww-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"154gamma # [335633.185034] gamma systemd[1]: Started D-Bus System Message Bus.155gamma # [335633.191727] gamma dbus-broker-launch[211]: Ready156gamma # [335633.286682] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.157alpha # [335632.861114] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...158alpha # [335632.896621] alpha systemd-resolved[108]: Positive Trust Anchors:159alpha # [335632.896633] alpha systemd-resolved[108]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d160alpha # [335632.896637] alpha systemd-resolved[108]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16161alpha # [335632.896670] alpha systemd-resolved[108]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test162alpha # [335632.902641] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.163alpha # [335632.918222] alpha systemd-resolved[108]: Using system hostname 'alpha'.164alpha # [335632.919582] alpha systemd[1]: Started Network Name Resolution.165alpha # [335632.919679] alpha systemd[1]: Reached target Network.166alpha # [335632.919763] alpha systemd[1]: Reached target System Initialization.167alpha # [335632.919822] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container168alpha # [335632.919858] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.169alpha # [335632.919880] alpha systemd[1]: Reached target Timer Units.170alpha # [335632.920026] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.171alpha # [335632.920203] alpha systemd[1]: Listening on Nix Daemon Socket.172alpha # [335632.920341] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.173alpha # [335632.920368] alpha systemd[1]: Reached target Socket Units.174alpha # [335632.920413] alpha systemd[1]: Reached target Basic System.175alpha # [335632.922061] alpha systemd[1]: Starting data mesher daemon...176alpha # [335632.923005] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...177alpha # [335632.924055] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...178alpha # [335632.925727] alpha systemd[1]: Starting D-Bus System Message Bus...179alpha # [335632.945060] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.180alpha # [335633.056308] alpha nsncd[212]: Sep 08 04:40:53.042 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"181alpha # [335633.056491] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).182alpha # [335633.056596] alpha systemd[1]: Reached target Host and Network Name Lookups.183alpha # [335633.056717] alpha systemd[1]: Reached target User and Group Name Lookups.184alpha # [335633.058948] alpha systemd[1]: Starting User Login Management...185alpha # [335633.060368] alpha systemd[1]: Starting Permit User Sessions...186alpha # [335633.070975] alpha systemd[1]: Finished Permit User Sessions.187alpha # [335633.072204] alpha systemd[1]: Started Console Getty.188alpha # [335633.072260] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0189alpha # [335633.072285] alpha systemd[1]: Reached target Login Prompts.190alpha # [335633.182054] alpha dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...191alpha # [335633.183497] alpha dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'192alpha # [335633.183497] alpha dbus-broker-launch[213]: Invalid user-name in /nix/store/y1xvnyfk8grlcjwhifz48bs8cwjiagww-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"193alpha # [335633.183963] alpha systemd[1]: Started D-Bus System Message Bus.194alpha # [335633.190927] alpha dbus-broker-launch[213]: Ready195alpha # [335633.274448] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.196beta # [335632.280201] beta systemd-journald[87]: Journal started197beta # [335632.280261] beta systemd-journald[87]: Runtime Journal (/run/log/journal/0c0d289b2e9746eaa9ae8a4a12a83439) is 8M, max 2.5G, 2.4G free.198beta # [335632.281651] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.199beta # [335632.290208] beta systemd[1]: Starting Flush Journal to Persistent Storage...200beta # [335632.291267] beta systemd[1]: Starting Network Name Resolution...201beta # [335632.291955] beta systemd[1]: Starting Create Static Device Nodes in /dev...202beta # [335632.301248] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/0c0d289b2e9746eaa9ae8a4a12a83439 is 2.253ms for 6 entries.203beta # [335632.301248] beta systemd-journald[87]: System Journal (/var/log/journal/0c0d289b2e9746eaa9ae8a4a12a83439) is 8M, max 4G, 3.9G free.204beta # [335632.305061] beta systemd[1]: Finished Create Static Device Nodes in /dev.205beta # [335632.305292] beta systemd[1]: Reached target Preparation for Local File Systems.206beta # [335632.305377] beta systemd[1]: Reached target Local File Systems.207beta # [335632.306119] beta systemd[1]: Listening on Boot Loader Control Service Socket.208beta # [335632.306166] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container209beta # [335632.307047] beta systemd[1]: Starting Save Transient machine-id to Disk...210beta # [335632.307103] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys211beta # [335632.349078] beta systemd[1]: Finished Flush Journal to Persistent Storage.212beta # [335632.350722] beta systemd[1]: Starting Create System Files and Directories...213beta # [335632.368439] beta systemd-tmpfiles[146]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted214beta # [335632.368662] beta systemd-tmpfiles[146]: fchmod() of /var/log/journal failed: Operation not permitted215beta # [335632.368811] beta systemd-tmpfiles[146]: fchmod() of /var/log/journal/0c0d289b2e9746eaa9ae8a4a12a83439 failed: Operation not permitted216beta # [335632.369032] beta systemd-tmpfiles[146]: fchmod() of /run/log/journal failed: Operation not permitted217beta # [335632.370551] beta systemd[1]: Finished Create System Files and Directories.218beta # [335632.371753] beta systemd[1]: Starting Rebuild Journal Catalog...219beta # [335632.372588] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...220beta # [335632.388852] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.221beta # [335632.394855] beta systemd[1]: Finished Rebuild Journal Catalog.222beta # [335632.396755] beta systemd[1]: Starting Update is Completed...223beta # [335632.408471] beta systemd[1]: Finished Update is Completed.224beta # [335632.446108] beta systemd[1]: Finished Firewall.225beta # [335632.446274] beta systemd[1]: Reached target Preparation for Network.226beta # [335632.446523] beta systemd[1]: Listening on Network Management Resolve Hook Socket.227beta # [335632.447586] beta systemd[1]: Starting Network Management...228beta # [335632.500400] beta systemd[1]: Finished Save Transient machine-id to Disk.229beta # [335632.834932] beta systemd-networkd[204]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted230beta # [335632.835029] beta systemd-networkd[204]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted231beta # [335632.842458] 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.232beta # [335632.842623] 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.233beta # [335632.842774] beta systemd-networkd[204]: lo: Link UP234beta # [335632.842777] beta systemd-networkd[204]: lo: Gained carrier235beta # [335632.842938] beta systemd-networkd[204]: eth1: Configuring with /etc/systemd/network/40-eth1.network.236beta # [335632.844044] beta systemd[1]: Started Network Management.237beta # [335632.860694] beta systemd-networkd[204]: eth1: Link UP238beta # [335632.861048] beta systemd-networkd[204]: eth1: Gained carrier239beta # [335632.861300] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...240beta # [335632.884352] beta systemd-resolved[110]: Positive Trust Anchors:241beta # [335632.884365] beta systemd-resolved[110]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d242beta # [335632.884369] beta systemd-resolved[110]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16243beta # [335632.884403] 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 test244beta # [335632.890571] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.245beta # [335632.906341] beta systemd-resolved[110]: Using system hostname 'beta'.246beta # [335632.907747] beta systemd[1]: Started Network Name Resolution.247beta # [335632.907889] beta systemd[1]: Reached target Network.248beta # [335632.908027] beta systemd[1]: Reached target System Initialization.249beta # [335632.908177] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container250beta # [335632.908232] beta systemd[1]: Started Daily Cleanup of Temporary Directories.251beta # [335632.908269] beta systemd[1]: Reached target Timer Units.252beta # [335632.908521] beta systemd[1]: Listening on D-Bus System Message Bus Socket.253beta # [335632.908752] beta systemd[1]: Listening on Nix Daemon Socket.254beta # [335632.908988] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.255beta # [335632.909037] beta systemd[1]: Reached target Socket Units.256beta # [335632.909221] beta systemd[1]: Reached target Basic System.257beta # [335632.911582] beta systemd[1]: Starting data mesher daemon...258beta # [335632.912850] beta systemd[1]: Starting Import lastlog data into lastlog2 database...259beta # [335632.914411] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...260beta # [335632.916824] beta systemd[1]: Starting D-Bus System Message Bus...261beta # [335632.937661] beta systemd[1]: Finished Import lastlog data into lastlog2 database.262beta # [335633.050712] beta nsncd[212]: Sep 08 04:40:53.036 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"263beta # [335633.050833] beta systemd[1]: Started Name Service Cache Daemon (nsncd).264beta # [335633.050908] beta systemd[1]: Reached target Host and Network Name Lookups.265beta # [335633.050982] beta systemd[1]: Reached target User and Group Name Lookups.266beta # [335633.052698] beta systemd[1]: Starting User Login Management...267beta # [335633.053751] beta systemd[1]: Starting Permit User Sessions...268beta # [335633.066252] beta systemd[1]: Finished Permit User Sessions.269beta # [335633.067503] beta systemd[1]: Started Console Getty.270beta # [335633.067554] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0271beta # [335633.067577] beta systemd[1]: Reached target Login Prompts.272beta # [335633.181341] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...273beta # [335633.182747] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'274beta # [335633.182747] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/y1xvnyfk8grlcjwhifz48bs8cwjiagww-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"275beta # [335633.183141] beta systemd[1]: Started D-Bus System Message Bus.276beta # [335633.191616] beta dbus-broker-launch[213]: Ready277beta # [335633.264478] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.278gamma # [335633.399120] gamma data-mesher[208]: time=2026-09-08T04:40:53.384Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]279gamma # [335633.400183] gamma data-mesher[208]: time=2026-09-08T04:40:53.385Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF: [/dns/alpha.clan/tcp/7946]} {12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV280beta # [335633.420057] beta data-mesher[210]: time=2026-09-08T04:40:53.405Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]281beta # [335633.421101] beta data-mesher[210]: time=2026-09-08T04:40:53.406Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF: [/dns/alpha.clan/tcp/7946]} {12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK282beta # [335633.421101] beta data-mesher[210]: time=2026-09-08T04:40:53.406Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml283beta # [335633.422168] beta data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="checking file integrity"284beta # [335633.422280] beta data-mesher[210]: time=2026-09-08T04:40:53.408Z level=INFO msg="file integrity check complete"285beta # [335633.426231] beta data-mesher[210]: time=2026-09-08T04:40:53.411Z level=INFO msg="libp2p host created" peer_id=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK 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]"286beta # [335633.426231] beta data-mesher[210]: time=2026-09-08T04:40:53.411Z level=INFO msg="registered HTTP route" method=GET path=/files287beta # [335633.426231] beta data-mesher[210]: time=2026-09-08T04:40:53.411Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name288beta # [335633.426231] beta data-mesher[210]: time=2026-09-08T04:40:53.411Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name289beta # [335633.426231] beta data-mesher[210]: time=2026-09-08T04:40:53.411Z level=INFO msg="starting server"290beta # [335633.426231] beta data-mesher[210]: time=2026-09-08T04:40:53.412Z level=INFO msg="waiting for DHT to populate" delay=10s291beta # [335633.426405] beta data-mesher[210]: time=2026-09-08T04:40:53.412Z level=INFO msg="HTTP server listening" address=[::1]:7331292beta # [335633.426405] beta data-mesher[210]: time=2026-09-08T04:40:53.412Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331293beta # [335633.431008] beta data-mesher[210]: time=2026-09-08T04:40:53.416Z level=INFO msg="peer connected" peer_id=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF remote_addr=/ip4/192.168.1.1/tcp/7946294beta # [335633.434467] beta data-mesher[210]: time=2026-09-08T04:40:53.420Z level=INFO msg="peer connected" peer_id=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV remote_addr=/ip4/192.168.1.3/tcp/7946295gamma # [335633.400183] gamma data-mesher[208]: time=2026-09-08T04:40:53.386Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml296gamma # [335633.417364] gamma data-mesher[208]: time=2026-09-08T04:40:53.403Z level=INFO msg="checking file integrity"297gamma # [335633.417464] gamma data-mesher[208]: time=2026-09-08T04:40:53.403Z level=INFO msg="file integrity check complete"298gamma # [335633.421747] gamma data-mesher[208]: time=2026-09-08T04:40:53.407Z level=INFO msg="libp2p host created" peer_id=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV 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]"299gamma # [335633.421747] gamma data-mesher[208]: time=2026-09-08T04:40:53.407Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name300gamma # [335633.421747] gamma data-mesher[208]: time=2026-09-08T04:40:53.407Z level=INFO msg="registered HTTP route" method=GET path=/files301gamma # [335633.421747] gamma data-mesher[208]: time=2026-09-08T04:40:53.407Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name302gamma # [335633.421747] gamma data-mesher[208]: time=2026-09-08T04:40:53.407Z level=INFO msg="starting server"303gamma # [335633.421942] gamma data-mesher[208]: time=2026-09-08T04:40:53.407Z level=INFO msg="waiting for DHT to populate" delay=10s304gamma # [335633.422131] gamma data-mesher[208]: time=2026-09-08T04:40:53.407Z level=INFO msg="HTTP server listening" address=[::1]:7331305gamma # [335633.422208] gamma data-mesher[208]: time=2026-09-08T04:40:53.408Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331306gamma # [335633.427409] gamma data-mesher[208]: time=2026-09-08T04:40:53.413Z level=INFO msg="peer connected" peer_id=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF remote_addr=/ip4/192.168.1.1/tcp/7946307gamma # [335633.433747] gamma data-mesher[208]: time=2026-09-08T04:40:53.419Z level=INFO msg="peer connected" peer_id=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK remote_addr=/ip4/192.168.1.2/tcp/7946308alpha # [335633.407094] alpha data-mesher[210]: time=2026-09-08T04:40:53.392Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]309alpha # [335633.408164] alpha data-mesher[210]: time=2026-09-08T04:40:53.393Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF: [/dns/alpha.clan/tcp/7946]} {12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF310alpha # [335633.408164] alpha data-mesher[210]: time=2026-09-08T04:40:53.393Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml311alpha # [335633.417464] alpha data-mesher[210]: time=2026-09-08T04:40:53.403Z level=INFO msg="checking file integrity"312alpha # [335633.417575] alpha data-mesher[210]: time=2026-09-08T04:40:53.403Z level=INFO msg="file integrity check complete"313alpha # [335633.421392] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="libp2p host created" peer_id=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF 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]"314alpha # [335633.421611] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="registered HTTP route" method=GET path=/files315alpha # [335633.421611] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name316alpha # [335633.421611] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name317alpha # [335633.421611] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="starting server"318alpha # [335633.421611] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="waiting for DHT to populate" delay=10s319alpha # [335633.421863] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="HTTP server listening" address=[::1]:7331320alpha # [335633.421863] alpha data-mesher[210]: time=2026-09-08T04:40:53.407Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331321alpha # [335633.428436] alpha data-mesher[210]: time=2026-09-08T04:40:53.414Z level=INFO msg="peer connected" peer_id=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV remote_addr=/ip4/192.168.1.3/tcp/7946322alpha # [335633.431712] alpha data-mesher[210]: time=2026-09-08T04:40:53.417Z level=INFO msg="peer connected" peer_id=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK remote_addr=/ip4/192.168.1.2/tcp/7946323beta # [335633.436815] beta data-mesher[210]: time=2026-09-08T04:40:53.422Z level=INFO msg="peer connected" peer_id=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV remote_addr=/ip4/192.168.1.3/tcp/7946324gamma # [335633.437705] gamma data-mesher[208]: time=2026-09-08T04:40:53.423Z level=INFO msg="peer connected" peer_id=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK remote_addr=/ip4/192.168.1.2/tcp/57308325gamma # [335633.549652] gamma systemd-logind[227]: New seat seat0.326beta # [335633.521001] beta systemd-logind[229]: New seat seat0.327gamma # [335633.549906] gamma systemd[1]: Started User Login Management.328beta # [335633.521186] beta systemd[1]: Started User Login Management.329gamma # [335633.551446] gamma systemd[1]: Starting linger-users.service...330beta # [335633.522382] beta systemd[1]: Starting linger-users.service...331beta # [335633.533623] beta systemd[1]: linger-users.service: Deactivated successfully.332beta # [335633.533754] beta systemd[1]: Finished linger-users.service.333gamma # [335633.564197] gamma systemd[1]: linger-users.service: Deactivated successfully.334gamma # [335633.564461] gamma systemd[1]: Finished linger-users.service.335alpha # [335633.511903] alpha systemd-logind[229]: New seat seat0.336alpha # [335633.512125] alpha systemd[1]: Started User Login Management.337alpha # [335633.514009] alpha systemd[1]: Starting linger-users.service...338alpha # [335633.525319] alpha systemd[1]: linger-users.service: Deactivated successfully.339alpha # [335633.525452] alpha systemd[1]: Finished linger-users.service.340beta # [335634.080321] beta systemd-networkd[204]: eth1: Gained IPv6LL341alpha # [335634.208399] alpha systemd-networkd[204]: eth1: Gained IPv6LL342gamma # [335634.464198] gamma systemd-networkd[203]: eth1: Gained IPv6LL343alpha: still waiting for container 'alpha' to reach ready state...344alpha: (finished: waiting for unit data-mesher.service, in 12.17 seconds)345beta: waiting for unit data-mesher.service346beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)347gamma: waiting for unit data-mesher.service348gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)349alpha: must succeed: echo -n 'hello world' > /tmp/test_file350alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)351alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key352alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.04 seconds)353??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.354 File "/nix/store/cag5izn0mcnmyi82zb3rwfs1s1afa6q5-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39355alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file356??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.357 File "/nix/store/cag5izn0mcnmyi82zb3rwfs1s1afa6q5-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39358alpha: (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)359beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file360gamma # [335643.423068] gamma data-mesher[208]: time=2026-09-08T04:41:03.408Z level=INFO msg="performing state exchange with peers on join" count=1361gamma # [335643.423730] gamma data-mesher[208]: time=2026-09-08T04:41:03.408Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s362gamma # [335643.423730] gamma data-mesher[208]: time=2026-09-08T04:41:03.409Z level=INFO msg="received state sync from peer" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF363gamma # [335643.423730] gamma data-mesher[208]: time=2026-09-08T04:41:03.409Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF364gamma # [335643.424071] gamma data-mesher[208]: time=2026-09-08T04:41:03.409Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF365gamma # [335643.424071] gamma data-mesher[208]: time=2026-09-08T04:41:03.409Z level=INFO msg="state exchange complete" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s366gamma # [335643.424200] gamma data-mesher[208]: time=2026-09-08T04:41:03.409Z level=INFO msg="server started"367gamma # [335643.424234] gamma data-mesher[208]: time=2026-09-08T04:41:03.410Z level=INFO msg="starting expired-file sweeper" interval=1m0s368gamma # [335643.424559] gamma systemd[1]: Started data mesher daemon.369gamma # [335643.425205] gamma systemd[1]: Reached target Multi-User System.370gamma # [335643.425531] gamma systemd[1]: Startup finished in 11.555s.371alpha # [335643.422837] alpha data-mesher[210]: time=2026-09-08T04:41:03.408Z level=INFO msg="performing state exchange with peers on join" count=1372alpha # [335643.423509] alpha data-mesher[210]: time=2026-09-08T04:41:03.408Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s373alpha # [335643.423945] alpha data-mesher[210]: time=2026-09-08T04:41:03.409Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV374alpha # [335643.423945] alpha data-mesher[210]: time=2026-09-08T04:41:03.409Z level=INFO msg="state exchange complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s375alpha # [335643.423945] alpha data-mesher[210]: time=2026-09-08T04:41:03.409Z level=INFO msg="received state sync from peer" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV376alpha # [335643.423945] alpha data-mesher[210]: time=2026-09-08T04:41:03.409Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV377alpha # [335643.423945] alpha data-mesher[210]: time=2026-09-08T04:41:03.409Z level=INFO msg="server started"378alpha # [335643.424264] alpha data-mesher[210]: time=2026-09-08T04:41:03.409Z level=INFO msg="starting expired-file sweeper" interval=1m0s379alpha # [335643.424248] alpha systemd[1]: Started data mesher daemon.380alpha # [335643.424777] alpha systemd[1]: Reached target Multi-User System.381alpha # [335643.425107] alpha systemd[1]: Startup finished in 11.573s.382alpha # [335643.427708] alpha data-mesher[210]: time=2026-09-08T04:41:03.413Z level=INFO msg="received state sync from peer" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK383alpha # [335643.427708] alpha data-mesher[210]: time=2026-09-08T04:41:03.413Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK384alpha # [335643.546834] alpha data-mesher[210]: time=2026-09-08T04:41:03.532Z level=INFO msg=http_request uri=/files/test_file status=204385beta # [335643.427054] beta data-mesher[210]: time=2026-09-08T04:41:03.412Z level=INFO msg="performing state exchange with peers on join" count=1386beta # [335643.427387] beta data-mesher[210]: time=2026-09-08T04:41:03.412Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s387beta # [335643.427918] beta data-mesher[210]: time=2026-09-08T04:41:03.413Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF388beta # [335643.427918] beta data-mesher[210]: time=2026-09-08T04:41:03.413Z level=INFO msg="state exchange complete" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s389beta # [335643.427983] beta data-mesher[210]: time=2026-09-08T04:41:03.413Z level=INFO msg="server started"390beta # [335643.428227] beta data-mesher[210]: time=2026-09-08T04:41:03.413Z level=INFO msg="starting expired-file sweeper" interval=1m0s391beta # [335643.428338] beta systemd[1]: Started data mesher daemon.392beta # [335643.428832] beta systemd[1]: Reached target Multi-User System.393beta # [335643.429132] beta systemd[1]: Startup finished in 11.589s.394beta # [335648.425183] beta data-mesher[210]: time=2026-09-08T04:41:08.410Z level=INFO msg="received state sync from peer" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV395gamma # [335648.424657] gamma data-mesher[208]: time=2026-09-08T04:41:08.410Z level=DEBUG msg="attempting push/pull" peer_count=2396beta # [335648.425183] beta data-mesher[210]: time=2026-09-08T04:41:08.411Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV397gamma # [335648.424657] gamma data-mesher[208]: time=2026-09-08T04:41:08.410Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s398beta # [335648.428825] beta data-mesher[210]: time=2026-09-08T04:41:08.414Z level=DEBUG msg="attempting push/pull" peer_count=2399alpha # [335648.424243] alpha data-mesher[210]: time=2026-09-08T04:41:08.409Z level=DEBUG msg="attempting push/pull" peer_count=2400alpha # [335648.424243] alpha data-mesher[210]: time=2026-09-08T04:41:08.410Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s401gamma # [335648.425427] gamma data-mesher[208]: time=2026-09-08T04:41:08.410Z level=INFO msg="received state sync from peer" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF402gamma # [335648.425427] gamma data-mesher[208]: time=2026-09-08T04:41:08.410Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF403beta # [335648.429745] beta data-mesher[210]: time=2026-09-08T04:41:08.414Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s404alpha # [335648.425032] alpha data-mesher[210]: time=2026-09-08T04:41:08.410Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV405gamma # [335648.425427] gamma data-mesher[208]: time=2026-09-08T04:41:08.410Z level=DEBUG msg="new file detected" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF name=test_file name=test_file406alpha # [335648.425032] alpha data-mesher[210]: time=2026-09-08T04:41:08.410Z level=INFO msg="state exchange complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s407gamma # [335648.425427] gamma data-mesher[208]: time=2026-09-08T04:41:08.410Z level=INFO msg="scheduling file download" name=test_file408alpha # [335648.425032] alpha data-mesher[210]: time=2026-09-08T04:41:08.410Z level=DEBUG msg="push/pull successful" interval=5s409beta # [335648.429745] beta data-mesher[210]: time=2026-09-08T04:41:08.415Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV410gamma # [335648.425427] gamma data-mesher[208]: time=2026-09-08T04:41:08.410Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-08 04:41:03.52 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF411alpha # [335648.425370] alpha data-mesher[210]: time=2026-09-08T04:41:08.411Z level=INFO msg="received file request" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_file412beta # [335648.429745] beta data-mesher[210]: time=2026-09-08T04:41:08.415Z level=INFO msg="state exchange complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s413beta # [335648.429745] beta data-mesher[210]: time=2026-09-08T04:41:08.415Z level=DEBUG msg="push/pull successful" interval=5s414gamma # [335648.425688] gamma data-mesher[208]: time=2026-09-08T04:41:08.411Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK415gamma # [335648.425688] gamma data-mesher[208]: time=2026-09-08T04:41:08.411Z level=INFO msg="state exchange complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s416gamma # [335648.425688] gamma data-mesher[208]: time=2026-09-08T04:41:08.411Z level=DEBUG msg="push/pull successful" interval=5s417gamma # [335648.429197] gamma data-mesher[208]: time=2026-09-08T04:41:08.415Z level=INFO msg="received state sync from peer" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK418gamma # [335648.429197] gamma data-mesher[208]: time=2026-09-08T04:41:08.415Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK419gamma # [335648.472227] gamma data-mesher[208]: time=2026-09-08T04:41:08.458Z level=INFO msg="download complete" name=test_file signed_at="2026-09-08 04:41:03.52 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF written=true elapsed=47.273213ms420alpha # [335648.426538] alpha data-mesher[210]: time=2026-09-08T04:41:08.412Z level=INFO msg="file transfer complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_file421beta: (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)422gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file423gamma: (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)424beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key425gamma # [335653.426518] gamma data-mesher[208]: time=2026-09-08T04:41:13.412Z level=DEBUG msg="attempting push/pull" peer_count=2426gamma # [335653.426955] gamma data-mesher[208]: time=2026-09-08T04:41:13.412Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s427gamma # [335653.427395] gamma data-mesher[208]: time=2026-09-08T04:41:13.413Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF428gamma # [335653.427464] gamma data-mesher[208]: time=2026-09-08T04:41:13.413Z level=INFO msg="state exchange complete" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s429gamma # [335653.427464] gamma data-mesher[208]: time=2026-09-08T04:41:13.413Z level=DEBUG msg="push/pull successful" interval=5s430beta # [335653.426394] beta data-mesher[210]: time=2026-09-08T04:41:13.412Z level=INFO msg="received state sync from peer" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF431beta # [335653.426394] beta data-mesher[210]: time=2026-09-08T04:41:13.412Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF432beta # [335653.427153] beta data-mesher[210]: time=2026-09-08T04:41:13.412Z level=DEBUG msg="new file detected" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF name=test_file name=test_file433beta # [335653.427153] beta data-mesher[210]: time=2026-09-08T04:41:13.412Z level=INFO msg="scheduling file download" name=test_file434beta # [335653.427153] beta data-mesher[210]: time=2026-09-08T04:41:13.412Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-08 04:41:03.52 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF435beta # [335653.430060] beta data-mesher[210]: time=2026-09-08T04:41:13.415Z level=DEBUG msg="attempting push/pull" peer_count=2436beta # [335653.430060] beta data-mesher[210]: time=2026-09-08T04:41:13.415Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s437beta # [335653.430956] beta data-mesher[210]: time=2026-09-08T04:41:13.416Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF438beta # [335653.430956] beta data-mesher[210]: time=2026-09-08T04:41:13.416Z level=DEBUG msg="new file detected" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF name=test_file name=test_file439beta # [335653.431130] beta data-mesher[210]: time=2026-09-08T04:41:13.416Z level=INFO msg="state exchange complete" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s440beta # [335653.431130] beta data-mesher[210]: time=2026-09-08T04:41:13.416Z level=DEBUG msg="push/pull successful" interval=5s441beta # [335653.464677] beta data-mesher[210]: time=2026-09-08T04:41:13.450Z level=INFO msg="download complete" name=test_file signed_at="2026-09-08 04:41:03.52 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF written=true elapsed=38.056405ms442beta # [335653.691308] beta data-mesher[210]: time=2026-09-08T04:41:13.677Z level=INFO msg=http_request uri=/files/test_file status=204443beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.05 seconds)444alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file445alpha # [335653.425487] alpha data-mesher[210]: time=2026-09-08T04:41:13.411Z level=DEBUG msg="attempting push/pull" peer_count=2446alpha # [335653.425956] alpha data-mesher[210]: time=2026-09-08T04:41:13.411Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s447alpha # [335653.426798] alpha data-mesher[210]: time=2026-09-08T04:41:13.412Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK448alpha # [335653.426798] alpha data-mesher[210]: time=2026-09-08T04:41:13.412Z level=INFO msg="state exchange complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s449alpha # [335653.426914] alpha data-mesher[210]: time=2026-09-08T04:41:13.412Z level=DEBUG msg="push/pull successful" interval=5s450alpha # [335653.427237] alpha data-mesher[210]: time=2026-09-08T04:41:13.412Z level=INFO msg="received state sync from peer" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV451alpha # [335653.427237] alpha data-mesher[210]: time=2026-09-08T04:41:13.413Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV452alpha # [335653.427237] alpha data-mesher[210]: time=2026-09-08T04:41:13.413Z level=INFO msg="received file request" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_file453alpha # [335653.428689] alpha data-mesher[210]: time=2026-09-08T04:41:13.414Z level=INFO msg="file transfer complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_file454alpha # [335653.430576] alpha data-mesher[210]: time=2026-09-08T04:41:13.416Z level=INFO msg="received state sync from peer" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK455alpha # [335653.430576] alpha data-mesher[210]: time=2026-09-08T04:41:13.416Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK456gamma # [335658.427869] gamma data-mesher[208]: time=2026-09-08T04:41:18.413Z level=DEBUG msg="attempting push/pull" peer_count=2457gamma # [335658.428257] gamma data-mesher[208]: time=2026-09-08T04:41:18.413Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s458gamma # [335658.428257] gamma data-mesher[208]: time=2026-09-08T04:41:18.413Z level=INFO msg="received state sync from peer" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF459gamma # [335658.428257] gamma data-mesher[208]: time=2026-09-08T04:41:18.413Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF460gamma # [335658.429863] gamma data-mesher[208]: time=2026-09-08T04:41:18.415Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK461gamma # [335658.433298] gamma data-mesher[208]: time=2026-09-08T04:41:18.419Z level=INFO msg="received state sync from peer" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK462gamma # [335658.433298] gamma data-mesher[208]: time=2026-09-08T04:41:18.419Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK463gamma # [335658.474782] gamma data-mesher[208]: time=2026-09-08T04:41:18.460Z level=DEBUG msg="imported tombstone" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK name=test_file written=true464gamma # [335658.474782] gamma data-mesher[208]: time=2026-09-08T04:41:18.460Z level=INFO msg="state exchange complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s465gamma # [335658.474897] gamma data-mesher[208]: time=2026-09-08T04:41:18.460Z level=DEBUG msg="push/pull successful" interval=5s466gamma # [335658.474897] gamma data-mesher[208]: time=2026-09-08T04:41:18.460Z level=DEBUG msg="imported tombstone" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK name=test_file written=false467alpha # [335658.427456] alpha data-mesher[210]: time=2026-09-08T04:41:18.413Z level=DEBUG msg="attempting push/pull" peer_count=2468alpha # [335658.428179] alpha data-mesher[210]: time=2026-09-08T04:41:18.413Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s469alpha # [335658.428363] alpha data-mesher[210]: time=2026-09-08T04:41:18.414Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV470alpha # [335658.428363] alpha data-mesher[210]: time=2026-09-08T04:41:18.414Z level=INFO msg="state exchange complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s471alpha # [335658.428491] alpha data-mesher[210]: time=2026-09-08T04:41:18.414Z level=DEBUG msg="push/pull successful" interval=5s472beta # [335658.428969] beta data-mesher[210]: time=2026-09-08T04:41:18.414Z level=INFO msg="received state sync from peer" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV473beta # [335658.428969] beta data-mesher[210]: time=2026-09-08T04:41:18.414Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV474beta # [335658.432064] beta data-mesher[210]: time=2026-09-08T04:41:18.417Z level=DEBUG msg="attempting push/pull" peer_count=2475beta # [335658.432216] beta data-mesher[210]: time=2026-09-08T04:41:18.417Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s476beta # [335658.475288] beta data-mesher[210]: time=2026-09-08T04:41:18.461Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV477beta # [335658.475441] beta data-mesher[210]: time=2026-09-08T04:41:18.461Z level=INFO msg="state exchange complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s478beta # [335658.475441] beta data-mesher[210]: time=2026-09-08T04:41:18.461Z level=DEBUG msg="push/pull successful" interval=5s479alpha # [335663.428852] alpha data-mesher[210]: time=2026-09-08T04:41:23.414Z level=DEBUG msg="attempting push/pull" peer_count=2480alpha # [335663.428852] alpha data-mesher[210]: time=2026-09-08T04:41:23.414Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s481alpha # [335663.429695] alpha data-mesher[210]: time=2026-09-08T04:41:23.415Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK482alpha # [335663.462669] alpha data-mesher[210]: time=2026-09-08T04:41:23.448Z level=DEBUG msg="imported tombstone" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK name=test_file written=true483alpha # [335663.462669] alpha data-mesher[210]: time=2026-09-08T04:41:23.448Z level=INFO msg="state exchange complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s484alpha # [335663.462831] alpha data-mesher[210]: time=2026-09-08T04:41:23.448Z level=DEBUG msg="push/pull successful" interval=5s485alpha # [335663.476361] alpha data-mesher[210]: time=2026-09-08T04:41:23.462Z level=INFO msg="received state sync from peer" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK486alpha # [335663.476361] alpha data-mesher[210]: time=2026-09-08T04:41:23.462Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK487alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.07 seconds)488beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file489beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)490gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file491gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)492alpha: must succeed: cat /nix/store/d3j06z7fcjjkl19hsb9wj5328xkl7fnw-per-machine-alpha-data-mesher-node-identity_identity.pub493beta # [335663.429308] beta data-mesher[210]: time=2026-09-08T04:41:23.415Z level=INFO msg="received state sync from peer" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF494beta # [335663.429308] beta data-mesher[210]: time=2026-09-08T04:41:23.415Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF495beta # [335663.475841] beta data-mesher[210]: time=2026-09-08T04:41:23.461Z level=DEBUG msg="attempting push/pull" peer_count=2496beta # [335663.475981] beta data-mesher[210]: time=2026-09-08T04:41:23.461Z level=DEBUG msg="initiating state exchange" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s497beta # [335663.476098] beta data-mesher[210]: time=2026-09-08T04:41:23.461Z level=INFO msg="received state sync from peer" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV498beta # [335663.476098] beta data-mesher[210]: time=2026-09-08T04:41:23.461Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV499beta # [335663.476694] beta data-mesher[210]: time=2026-09-08T04:41:23.462Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF500beta # [335663.476748] beta data-mesher[210]: time=2026-09-08T04:41:23.462Z level=INFO msg="state exchange complete" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF timeout=5s501beta # [335663.476783] beta data-mesher[210]: time=2026-09-08T04:41:23.462Z level=DEBUG msg="push/pull successful" interval=5s502alpha: (finished: must succeed: cat /nix/store/d3j06z7fcjjkl19hsb9wj5328xkl7fnw-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)503alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file504alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.00 seconds)505alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert506alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)507alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg508alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg, in 0.01 seconds)509beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg510gamma # [335663.475519] gamma data-mesher[208]: time=2026-09-08T04:41:23.461Z level=DEBUG msg="attempting push/pull" peer_count=2511gamma # [335663.475980] gamma data-mesher[208]: time=2026-09-08T04:41:23.461Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s512gamma # [335663.476318] gamma data-mesher[208]: time=2026-09-08T04:41:23.462Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK513gamma # [335663.476366] gamma data-mesher[208]: time=2026-09-08T04:41:23.462Z level=INFO msg="state exchange complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s514gamma # [335663.476366] gamma data-mesher[208]: time=2026-09-08T04:41:23.462Z level=DEBUG msg="push/pull successful" interval=5s515alpha # [335663.819406] alpha data-mesher[210]: time=2026-09-08T04:41:23.805Z level=INFO msg=http_request uri=/files/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg status=204516alpha # [335668.463158] alpha data-mesher[210]: time=2026-09-08T04:41:28.448Z level=DEBUG msg="attempting push/pull" peer_count=2517alpha # [335668.463522] alpha data-mesher[210]: time=2026-09-08T04:41:28.449Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s518alpha # [335668.464811] alpha data-mesher[210]: time=2026-09-08T04:41:28.450Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV519alpha # [335668.464853] alpha data-mesher[210]: time=2026-09-08T04:41:28.450Z level=INFO msg="state exchange complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s520alpha # [335668.464877] alpha data-mesher[210]: time=2026-09-08T04:41:28.450Z level=DEBUG msg="push/pull successful" interval=5s521alpha # [335668.465110] alpha data-mesher[210]: time=2026-09-08T04:41:28.450Z level=INFO msg="received file request" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg522alpha # [335668.466836] alpha data-mesher[210]: time=2026-09-08T04:41:28.452Z level=INFO msg="file transfer complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg523beta # [335668.477659] beta data-mesher[210]: time=2026-09-08T04:41:28.463Z level=DEBUG msg="attempting push/pull" peer_count=2524beta # [335668.477659] beta data-mesher[210]: time=2026-09-08T04:41:28.463Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s525beta # [335668.478319] beta data-mesher[210]: time=2026-09-08T04:41:28.463Z level=INFO msg="received state sync from peer" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV526beta # [335668.478319] beta data-mesher[210]: time=2026-09-08T04:41:28.463Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV527beta # [335668.479032] beta data-mesher[210]: time=2026-09-08T04:41:28.464Z level=DEBUG msg="new file detected" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV name=test_file name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg528beta # [335668.479032] beta data-mesher[210]: time=2026-09-08T04:41:28.464Z level=INFO msg="scheduling file download" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg529beta # [335668.479032] beta data-mesher[210]: time=2026-09-08T04:41:28.464Z level=INFO msg="downloading file" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg signed_at="2026-09-08 04:41:23.802 +0000 UTC" signed_by="2GFy8nmXucKdKlOwe0k+X6ax3+uZyzVyCIRLL31a1Hg=" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV530beta # [335668.479032] beta data-mesher[210]: time=2026-09-08T04:41:28.464Z level=INFO msg="merging remote state" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV531beta # [335668.479639] beta data-mesher[210]: time=2026-09-08T04:41:28.465Z level=DEBUG msg="new file detected" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV name=test_file name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg532beta # [335668.479639] beta data-mesher[210]: time=2026-09-08T04:41:28.465Z level=INFO msg="state exchange complete" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV timeout=5s533beta # [335668.479755] beta data-mesher[210]: time=2026-09-08T04:41:28.465Z level=DEBUG msg="push/pull successful" interval=5s534beta # [335668.483643] beta data-mesher[210]: time=2026-09-08T04:41:28.469Z level=INFO msg="download complete" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg signed_at="2026-09-08 04:41:23.802 +0000 UTC" signed_by="2GFy8nmXucKdKlOwe0k+X6ax3+uZyzVyCIRLL31a1Hg=" peer=12D3KooWHgrLryT1z2FHL3NbgJvtuAYWN89Ye5K4vtyUhPmJy6KV written=true elapsed=4.828947ms535beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg, in 5.05 seconds)536gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg537gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg, in 0.01 seconds)538alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert539alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)540alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub541Error: failed to update file: 403 Forbidden, signer 2GFy8nmXucKdKlOwe0k+X6ax3+uZyzVyCIRLL31a1Hg= is not authorized for this file test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg542alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/4n0nbkr9kmhbv56svznaxhrayq0c8r70-shared-data-mesher-network_network.pub, in 0.03 seconds)543(finished: run the VM test script, in 37.62 seconds)544gamma # [335668.464018] gamma data-mesher[208]: time=2026-09-08T04:41:28.449Z level=INFO msg="received state sync from peer" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF545gamma # [335668.464018] gamma data-mesher[208]: time=2026-09-08T04:41:28.449Z level=INFO msg="merging remote state" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF546gamma # [335668.466448] gamma data-mesher[208]: time=2026-09-08T04:41:28.450Z level=DEBUG msg="new file detected" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF name=test_file name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg547gamma # [335668.466448] gamma data-mesher[208]: time=2026-09-08T04:41:28.450Z level=INFO msg="scheduling file download" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg548gamma # [335668.466448] gamma data-mesher[208]: time=2026-09-08T04:41:28.450Z level=INFO msg="downloading file" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg signed_at="2026-09-08 04:41:23.802 +0000 UTC" signed_by="2GFy8nmXucKdKlOwe0k+X6ax3+uZyzVyCIRLL31a1Hg=" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF549gamma # [335668.468352] gamma data-mesher[208]: time=2026-09-08T04:41:28.454Z level=INFO msg="download complete" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg signed_at="2026-09-08 04:41:23.802 +0000 UTC" signed_by="2GFy8nmXucKdKlOwe0k+X6ax3+uZyzVyCIRLL31a1Hg=" peer=12D3KooWQP2R5S52G6a6AbmVaXyp3fWBMLnjn83Mtwpn8azu1iYF written=true elapsed=3.585849ms550gamma # [335668.477308] gamma data-mesher[208]: time=2026-09-08T04:41:28.463Z level=DEBUG msg="attempting push/pull" peer_count=2551gamma # [335668.477389] gamma data-mesher[208]: time=2026-09-08T04:41:28.463Z level=DEBUG msg="initiating state exchange" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s552gamma # [335668.478664] gamma data-mesher[208]: time=2026-09-08T04:41:28.464Z level=INFO msg="received state sync from peer" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK553gamma # [335668.478664] gamma data-mesher[208]: time=2026-09-08T04:41:28.464Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK554gamma # [335668.478900] gamma data-mesher[208]: time=2026-09-08T04:41:28.464Z level=INFO msg="merging remote state" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK555gamma # [335668.478900] gamma data-mesher[208]: time=2026-09-08T04:41:28.464Z level=INFO msg="state exchange complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK timeout=5s556gamma # [335668.479005] gamma data-mesher[208]: time=2026-09-08T04:41:28.464Z level=DEBUG msg="push/pull successful" interval=5s557gamma # [335668.479234] gamma data-mesher[208]: time=2026-09-08T04:41:28.465Z level=INFO msg="received file request" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg558gamma # [335668.482066] gamma data-mesher[208]: time=2026-09-08T04:41:28.467Z level=INFO msg="file transfer complete" peer=12D3KooWHTLkmRgKbn7rxXnCKGwdBb2jPYWaQu26gZb3VTo74MvK network="W5pixqkMDEzxqoFyvy6bNywd9BucxjbFoAi6H8J0cpw=" name=test_ns/2GFy8nmXucKdKlOwe0k-X6ax3-uZyzVyCIRLL31a1Hg559test script finished in 37.77s560cleanup561kill NspawnMachine (pid 52)562kill NspawnMachine (pid 54)563Container alpha terminated by signal KILL.564kill NspawnMachine (pid 56)565Container beta terminated by signal KILL.566Container gamma terminated by signal KILL.567(finished: cleanup, in 0.59 seconds)

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

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