container-test-run-data-mesher log

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

1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 alpha, beta, gamma,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12alpha: systemd-nspawn running (pid 53)13beta: systemd-nspawn running (pid 54)14gamma: systemd-nspawn running (pid 55)15alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...16gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...17(finished: start all VMs, in 0.00 seconds)18beta: Waiting for journal at /build/vm-state-beta/var/log/journal...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.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.28░ Spawning container alpha on /build/vm-state-alpha.29░ Spawning container gamma on /build/vm-state-gamma.30Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.31░ Spawning container beta on /build/vm-state-beta.32alpha # [94550.874818] alpha systemd-journald[88]: Journal started33alpha # [94550.874868] alpha systemd-journald[88]: Runtime Journal (/run/log/journal/262adbea8f414996af715d86de7cbb39) is 8M, max 2.5G, 2.4G free.34alpha # [94550.879481] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.35alpha # [94550.888237] alpha systemd[1]: Starting Flush Journal to Persistent Storage...36alpha # [94550.889176] alpha systemd[1]: Starting Network Name Resolution...37alpha # [94550.889897] alpha systemd[1]: Starting Create Static Device Nodes in /dev...38alpha # [94550.897797] alpha systemd-journald[88]: Time spent on flushing to /var/log/journal/262adbea8f414996af715d86de7cbb39 is 1.608ms for 6 entries.39alpha # [94550.897797] alpha systemd-journald[88]: System Journal (/var/log/journal/262adbea8f414996af715d86de7cbb39) is 8M, max 4G, 3.9G free.40alpha # [94550.905135] alpha systemd[1]: Finished Create Static Device Nodes in /dev.41alpha # [94550.905388] alpha systemd[1]: Reached target Preparation for Local File Systems.42alpha # [94550.905480] alpha systemd[1]: Reached target Local File Systems.43alpha # [94550.906230] alpha systemd[1]: Listening on Boot Loader Control Service Socket.44alpha # [94550.906278] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container45alpha # [94550.907174] alpha systemd[1]: Starting Save Transient machine-id to Disk...46alpha # [94550.907210] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys47alpha # [94550.923008] alpha systemd[1]: Finished Flush Journal to Persistent Storage.48alpha # [94550.924537] alpha systemd[1]: Starting Create System Files and Directories...49alpha # [94550.945330] alpha systemd-tmpfiles[137]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted50alpha # [94550.945520] alpha systemd-tmpfiles[137]: fchmod() of /var/log/journal failed: Operation not permitted51alpha # [94550.945651] alpha systemd-tmpfiles[137]: fchmod() of /var/log/journal/262adbea8f414996af715d86de7cbb39 failed: Operation not permitted52alpha # [94550.945851] alpha systemd-tmpfiles[137]: fchmod() of /run/log/journal failed: Operation not permitted53alpha # [94550.947276] alpha systemd[1]: Finished Create System Files and Directories.54alpha # [94550.948373] alpha systemd[1]: Starting Rebuild Journal Catalog...55alpha # [94550.949319] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...56alpha # [94550.960920] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.57alpha # [94550.967422] alpha systemd[1]: Finished Rebuild Journal Catalog.58alpha # [94550.968525] alpha systemd[1]: Starting Update is Completed...59alpha # [94550.979746] alpha systemd[1]: Finished Update is Completed.60gamma # [94550.879623] gamma systemd-journald[87]: Journal started61gamma # [94550.879682] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/40e4e744cf314a93b264a79557460c0f) is 8M, max 2.5G, 2.4G free.62gamma # [94550.887769] gamma systemd[1]: Starting Flush Journal to Persistent Storage...63gamma # [94550.888629] gamma systemd[1]: Starting Network Name Resolution...64gamma # [94550.889341] gamma systemd[1]: Starting Create Static Device Nodes in /dev...65gamma # [94550.896907] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/40e4e744cf314a93b264a79557460c0f is 1.595ms for 5 entries.66gamma # [94550.896907] gamma systemd-journald[87]: System Journal (/var/log/journal/40e4e744cf314a93b264a79557460c0f) is 8M, max 4G, 3.9G free.67gamma # [94550.905148] gamma systemd[1]: Finished Create Static Device Nodes in /dev.68gamma # [94550.905397] gamma systemd[1]: Reached target Preparation for Local File Systems.69gamma # [94550.905487] gamma systemd[1]: Reached target Local File Systems.70gamma # [94550.906233] gamma systemd[1]: Listening on Boot Loader Control Service Socket.71gamma # [94550.906277] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container72gamma # [94550.907175] gamma systemd[1]: Starting Save Transient machine-id to Disk...73gamma # [94550.907210] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys74gamma # [94550.922564] gamma systemd[1]: Finished Flush Journal to Persistent Storage.75gamma # [94550.924196] gamma systemd[1]: Starting Create System Files and Directories...76gamma # [94550.945606] gamma systemd-tmpfiles[134]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted77gamma # [94550.945830] gamma systemd-tmpfiles[134]: fchmod() of /var/log/journal failed: Operation not permitted78gamma # [94550.945984] gamma systemd-tmpfiles[134]: fchmod() of /var/log/journal/40e4e744cf314a93b264a79557460c0f failed: Operation not permitted79gamma # [94550.946208] gamma systemd-tmpfiles[134]: fchmod() of /run/log/journal failed: Operation not permitted80gamma # [94550.948857] gamma systemd[1]: Finished Create System Files and Directories.81gamma # [94550.950299] gamma systemd[1]: Starting Rebuild Journal Catalog...82gamma # [94550.951057] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...83gamma # [94550.962618] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.84gamma # [94550.970995] gamma systemd[1]: Finished Rebuild Journal Catalog.85gamma # [94550.971972] gamma systemd[1]: Starting Update is Completed...86beta # [94550.907395] beta systemd-journald[88]: Journal started87beta # [94550.907456] beta systemd-journald[88]: Runtime Journal (/run/log/journal/ba758b4a027b4a04833bf49c45c0abfb) is 8M, max 2.5G, 2.4G free.88beta # [94550.910807] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.89beta # [94550.919006] beta systemd[1]: Starting Flush Journal to Persistent Storage...90beta # [94550.919953] beta systemd[1]: Starting Network Name Resolution...91beta # [94550.920749] beta systemd[1]: Starting Create Static Device Nodes in /dev...92beta # [94550.929172] beta systemd-journald[88]: Time spent on flushing to /var/log/journal/ba758b4a027b4a04833bf49c45c0abfb is 1.576ms for 6 entries.93beta # [94550.929172] beta systemd-journald[88]: System Journal (/var/log/journal/ba758b4a027b4a04833bf49c45c0abfb) is 8M, max 4G, 3.9G free.94beta # [94550.933060] beta systemd[1]: Finished Create Static Device Nodes in /dev.95beta # [94550.933787] beta systemd[1]: Reached target Preparation for Local File Systems.96beta # [94550.933916] beta systemd[1]: Reached target Local File Systems.97beta # [94550.934787] beta systemd[1]: Listening on Boot Loader Control Service Socket.98beta # [94550.934839] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container99beta # [94550.935806] beta systemd[1]: Starting Save Transient machine-id to Disk...100beta # [94550.935840] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys101beta # [94550.945935] beta systemd[1]: Finished Flush Journal to Persistent Storage.102beta # [94550.947170] beta systemd[1]: systemd-tmpfiles-setup.service: Failed to spawn executor: No such file or directory103beta # [94550.947191] beta systemd[1]: systemd-tmpfiles-setup.service: Failed to spawn 'start' task: No such file or directory104beta # [94550.947227] beta systemd[1]: systemd-tmpfiles-setup.service: Failed with result 'resources'.105beta # [94550.947384] beta systemd[1]: Failed to start Create System Files and Directories.106beta # [94550.948345] beta systemd[1]: Starting Rebuild Journal Catalog...107beta # [94550.949323] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...108beta # [94550.961256] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.109beta # [94550.968332] beta systemd[1]: Finished Rebuild Journal Catalog.110beta # [94550.969902] beta systemd[1]: Starting Update is Completed...111alpha # [94550.994354] alpha systemd[1]: Finished Save Transient machine-id to Disk.112alpha # [94551.033935] alpha systemd[1]: Finished Firewall.113alpha # [94551.034119] alpha systemd[1]: Reached target Preparation for Network.114alpha # [94551.034387] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.115alpha # [94551.035598] alpha systemd[1]: Starting Network Management...116gamma # [94550.981973] gamma systemd[1]: Finished Update is Completed.117gamma # [94550.994353] gamma systemd[1]: Finished Save Transient machine-id to Disk.118gamma # [94551.035995] gamma systemd[1]: Finished Firewall.119gamma # [94551.036169] gamma systemd[1]: Reached target Preparation for Network.120gamma # [94551.036371] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.121gamma # [94551.037321] gamma systemd[1]: Starting Network Management...122beta # [94550.981966] beta systemd[1]: Finished Update is Completed.123beta # [94550.994361] beta systemd[1]: Finished Save Transient machine-id to Disk.124beta # [94551.064891] beta systemd[1]: Finished Firewall.125beta # [94551.065044] beta systemd[1]: Reached target Preparation for Network.126beta # [94551.065258] beta systemd[1]: Listening on Network Management Resolve Hook Socket.127beta # [94551.066225] beta systemd[1]: Starting Network Management...128beta # [94551.391524] beta systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted129beta # [94551.391615] beta systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted130beta # [94551.398189] beta systemd-networkd[205]: /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.131beta # [94551.398355] beta systemd-networkd[205]: /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.132beta # [94551.398504] beta systemd-networkd[205]: lo: Link UP133beta # [94551.398509] beta systemd-networkd[205]: lo: Gained carrier134beta # [94551.398681] beta systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.135beta # [94551.399125] beta systemd[1]: Started Network Management.136beta # [94551.399171] beta systemd-networkd[205]: eth1: Link UP137beta # [94551.399504] beta systemd-networkd[205]: eth1: Gained carrier138beta # [94551.400543] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...139gamma # [94551.404990] gamma systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted140gamma # [94551.405076] gamma systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted141beta # [94551.441724] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.142gamma # [94551.411373] gamma systemd-networkd[205]: /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 # [94551.411538] gamma systemd-networkd[205]: /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 # [94551.411668] gamma systemd-networkd[205]: lo: Link UP145gamma # [94551.411673] gamma systemd-networkd[205]: lo: Gained carrier146gamma # [94551.411846] gamma systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.147gamma # [94551.412228] gamma systemd[1]: Started Network Management.148gamma # [94551.432555] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...149gamma # [94551.432650] gamma systemd-networkd[205]: eth1: Link UP150gamma # [94551.433237] gamma systemd-networkd[205]: eth1: Gained carrier151gamma # [94551.466245] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.152gamma # [94551.505619] gamma systemd-resolved[109]: Positive Trust Anchors:153gamma # [94551.505630] gamma systemd-resolved[109]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d154gamma # [94551.505633] gamma systemd-resolved[109]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16155gamma # [94551.505669] gamma systemd-resolved[109]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test156gamma # [94551.527419] gamma systemd-resolved[109]: Using system hostname 'gamma'.157gamma # [94551.528814] gamma systemd[1]: Started Network Name Resolution.158gamma # [94551.528946] gamma systemd[1]: Reached target Network.159gamma # [94551.529075] gamma systemd[1]: Reached target System Initialization.160gamma # [94551.529178] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container161gamma # [94551.529230] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.162gamma # [94551.529269] gamma systemd[1]: Reached target Timer Units.163gamma # [94551.529491] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.164gamma # [94551.529719] gamma systemd[1]: Listening on Nix Daemon Socket.165gamma # [94551.529948] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.166gamma # [94551.529995] gamma systemd[1]: Reached target Socket Units.167gamma # [94551.530085] gamma systemd[1]: Reached target Basic System.168gamma # [94551.564608] gamma systemd[1]: Starting data mesher daemon...169gamma # [94551.565938] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...170gamma # [94551.567279] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...171gamma # [94551.569697] gamma systemd[1]: Starting D-Bus System Message Bus...172gamma # [94551.588659] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.173gamma # [94551.733083] gamma nsncd[212]: Sep 05 09:42:51.718 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"174gamma # [94551.733101] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).175gamma # [94551.733154] gamma systemd[1]: Reached target Host and Network Name Lookups.176gamma # [94551.733205] gamma systemd[1]: Reached target User and Group Name Lookups.177beta # [94551.505812] beta systemd-resolved[112]: Positive Trust Anchors:178beta # [94551.505821] beta systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d179beta # [94551.505824] beta systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16180beta # [94551.505858] beta 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 test181beta # [94551.527472] beta systemd-resolved[112]: Using system hostname 'beta'.182beta # [94551.528789] beta systemd[1]: Started Network Name Resolution.183beta # [94551.528886] beta systemd[1]: Reached target Network.184beta # [94551.528966] beta systemd[1]: Reached target System Initialization.185beta # [94551.529032] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container186beta # [94551.529068] beta systemd[1]: Started Daily Cleanup of Temporary Directories.187beta # [94551.529090] beta systemd[1]: Reached target Timer Units.188beta # [94551.529251] beta systemd[1]: Listening on D-Bus System Message Bus Socket.189beta # [94551.529296] beta systemd[1]: Nix Daemon Socket skipped, unmet condition check ConditionPathIsReadWrite=/nix/var/nix/daemon-socket190beta # [94551.529424] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.191beta # [94551.529454] beta systemd[1]: Reached target Socket Units.192beta # [94551.529503] beta systemd[1]: Reached target Basic System.193beta # [94551.529722] beta systemd[1]: System is tainted: var-run-bad194beta # [94551.564528] beta systemd[1]: Starting data mesher daemon...195beta # [94551.564590] beta systemd[1]: Import lastlog data into lastlog2 database skipped, unmet condition check ConditionPathExists=/var/log/lastlog196beta # [94551.565743] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...197beta # [94551.567360] beta systemd[1]: Starting D-Bus System Message Bus...198beta # [94551.733094] beta nsncd[211]: Sep 05 09:42:51.718 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"199beta # [94551.733094] beta nsncd[211]: Error: Read-only file system (os error 30)200alpha # [94551.402743] alpha systemd-networkd[206]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted201alpha # [94551.402833] alpha systemd-networkd[206]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted202alpha # [94551.409441] alpha systemd-networkd[206]: /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.203alpha # [94551.409607] alpha systemd-networkd[206]: /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.204alpha # [94551.409754] alpha systemd-networkd[206]: lo: Link UP205alpha # [94551.409758] alpha systemd-networkd[206]: lo: Gained carrier206alpha # [94551.409953] alpha systemd-networkd[206]: eth1: Configuring with /etc/systemd/network/40-eth1.network.207alpha # [94551.410324] alpha systemd[1]: Started Network Management.208alpha # [94551.432317] alpha systemd-networkd[206]: eth1: Link UP209alpha # [94551.432426] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...210alpha # [94551.432734] alpha systemd-networkd[206]: eth1: Gained carrier211alpha # [94551.466154] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.212alpha # [94551.495464] alpha systemd-resolved[111]: Positive Trust Anchors:213alpha # [94551.495475] alpha systemd-resolved[111]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d214alpha # [94551.495479] alpha systemd-resolved[111]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16215alpha # [94551.495516] alpha systemd-resolved[111]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test216alpha # [94551.517590] alpha systemd-resolved[111]: Using system hostname 'alpha'.217alpha # [94551.518952] alpha systemd[1]: Started Network Name Resolution.218alpha # [94551.519050] alpha systemd[1]: Reached target Network.219alpha # [94551.519129] alpha systemd[1]: Reached target System Initialization.220alpha # [94551.519189] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container221alpha # [94551.519221] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.222alpha # [94551.519241] alpha systemd[1]: Reached target Timer Units.223alpha # [94551.519378] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.224alpha # [94551.519522] alpha systemd[1]: Listening on Nix Daemon Socket.225alpha # [94551.519651] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.226alpha # [94551.519674] alpha systemd[1]: Reached target Socket Units.227alpha # [94551.519716] alpha systemd[1]: Reached target Basic System.228alpha # [94551.521244] alpha systemd[1]: Starting data mesher daemon...229alpha # [94551.522259] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...230alpha # [94551.523247] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...231alpha # [94551.524730] alpha systemd[1]: Starting D-Bus System Message Bus...232alpha # [94551.582511] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.233alpha # [94551.734633] alpha nsncd[213]: Sep 05 09:42:51.720 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"234alpha # [94551.734593] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).235alpha # [94551.734653] alpha systemd[1]: Reached target Host and Network Name Lookups.236alpha # [94551.734725] alpha systemd[1]: Reached target User and Group Name Lookups.237beta # [94551.736432] beta systemd[1]: nscd.service: Main process exited, code=exited, status=1/FAILURE238beta # [94551.736640] beta systemd[1]: nscd.service: Failed with result 'exit-code'.239beta # [94551.736890] beta systemd[1]: Failed to start Name Service Cache Daemon (nsncd).240beta # [94551.736917] beta systemd[1]: Dependency failed for User and Group Name Lookups.241beta # [94551.736937] beta systemd[1]: nss-user-lookup.target: Job nss-user-lookup.target/start failed with result 'dependency'.242beta # [94551.736961] beta systemd[1]: Dependency failed for Host and Network Name Lookups.243beta # [94551.736979] beta systemd[1]: nss-lookup.target: Job nss-lookup.target/start failed with result 'dependency'.244beta # [94551.741994] beta systemd[1]: nscd.service: Scheduled restart job immediately on client request, restart counter is at 1.245beta # [94551.772928] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...246beta # [94551.834521] beta dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...247beta # [94551.835122] beta dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'248beta # [94551.835122] beta dbus-broker-launch[212]: Invalid user-name in /nix/store/y1xvnyfk8grlcjwhifz48bs8cwjiagww-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"249beta # [94551.835518] beta systemd[1]: Started D-Bus System Message Bus.250beta # [94551.842852] beta dbus-broker-launch[212]: Ready251beta # [94551.874439] beta nsncd[219]: Sep 05 09:42:51.860 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"252beta # [94551.874741] beta nsncd[219]: Error: Read-only file system (os error 30)253beta # [94551.877436] beta systemd[1]: nscd.service: Main process exited, code=exited, status=1/FAILURE254beta # [94551.877650] beta systemd[1]: nscd.service: Failed with result 'exit-code'.255beta # [94551.877823] beta systemd[1]: Failed to start Name Service Cache Daemon (nsncd).256beta # [94551.877929] beta systemd[1]: Dependency failed for User and Group Name Lookups.257beta # [94551.877945] beta systemd[1]: nss-user-lookup.target: Job nss-user-lookup.target/start failed with result 'dependency'.258beta # [94551.877964] beta systemd[1]: Dependency failed for Host and Network Name Lookups.259beta # [94551.877983] beta systemd[1]: nss-lookup.target: Job nss-lookup.target/start failed with result 'dependency'.260beta # [94551.880372] beta systemd[1]: nscd.service: Scheduled restart job immediately on client request, restart counter is at 2.261beta # [94551.881582] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...262beta # [94551.890497] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.263alpha # [94551.736640] alpha systemd[1]: Starting User Login Management...264alpha # [94551.737560] alpha systemd[1]: Starting Permit User Sessions...265alpha # [94551.780103] alpha systemd[1]: Finished Permit User Sessions.266alpha # [94551.781322] alpha systemd[1]: Started Console Getty.267alpha # [94551.781376] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0268alpha # [94551.781401] alpha systemd[1]: Reached target Login Prompts.269alpha # [94551.856151] alpha dbus-broker-launch[214]: Looking up NSS user entry for 'systemd-timesync'...270alpha # [94551.857318] alpha dbus-broker-launch[214]: NSS returned no entry for 'systemd-timesync'271alpha # [94551.857318] alpha dbus-broker-launch[214]: Invalid user-name in /nix/store/y1xvnyfk8grlcjwhifz48bs8cwjiagww-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"272alpha # [94551.857706] alpha systemd[1]: Started D-Bus System Message Bus.273alpha # [94551.866460] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.274alpha # [94551.871081] alpha dbus-broker-launch[214]: Ready275gamma # [94551.734774] gamma systemd[1]: Starting User Login Management...276gamma # [94551.735767] gamma systemd[1]: Starting Permit User Sessions...277gamma # [94551.780184] gamma systemd[1]: Finished Permit User Sessions.278gamma # [94551.781819] gamma systemd[1]: Started Console Getty.279gamma # [94551.781902] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0280gamma # [94551.781946] gamma systemd[1]: Reached target Login Prompts.281gamma # [94551.817014] gamma dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...282gamma # [94551.818376] gamma dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'283gamma # [94551.818376] gamma 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"284gamma # [94551.819066] gamma systemd[1]: Started D-Bus System Message Bus.285gamma # [94551.826355] gamma dbus-broker-launch[213]: Ready286gamma # [94551.865964] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.287beta # [94552.024635] beta nsncd[226]: Sep 05 09:42:52.010 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"288beta # [94552.024635] beta nsncd[226]: Error: Read-only file system (os error 30)289beta # [94552.027338] beta systemd[1]: nscd.service: Main process exited, code=exited, status=1/FAILURE290beta # [94552.027498] beta systemd[1]: nscd.service: Failed with result 'exit-code'.291beta # [94552.027709] beta systemd[1]: Failed to start Name Service Cache Daemon (nsncd).292beta # [94552.027889] beta systemd[1]: Dependency failed for User and Group Name Lookups.293beta # [94552.027909] beta systemd[1]: nss-user-lookup.target: Job nss-user-lookup.target/start failed with result 'dependency'.294beta # [94552.027929] beta systemd[1]: Dependency failed for Host and Network Name Lookups.295beta # [94552.027950] beta systemd[1]: nss-lookup.target: Job nss-lookup.target/start failed with result 'dependency'.296beta # [94552.030245] beta systemd[1]: nscd.service: Start request repeated too quickly.297beta # [94552.030256] beta systemd[1]: nscd.service: Failed with result 'start-limit-hit'.298beta # [94552.030442] beta systemd[1]: Failed to start Name Service Cache Daemon (nsncd).299beta # [94552.030542] beta systemd[1]: Dependency failed for User and Group Name Lookups.300beta # [94552.030559] beta systemd[1]: nss-user-lookup.target: Job nss-user-lookup.target/start failed with result 'dependency'.301beta # [94552.030569] beta systemd[1]: Dependency failed for Host and Network Name Lookups.302beta # [94552.030582] beta systemd[1]: nss-lookup.target: Job nss-lookup.target/start failed with result 'dependency'.303beta # [94552.031985] beta systemd[1]: Starting User Login Management...304beta # [94552.032895] beta systemd[1]: Starting Permit User Sessions...305beta # [94552.080871] beta data-mesher[210]: time=2026-09-05T09:42:52.066Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]306beta # [94552.081953] beta data-mesher[210]: time=2026-09-05T09:42:52.067Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ: [/dns/alpha.clan/tcp/7946]} {12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y307beta # [94552.081985] beta data-mesher[210]: time=2026-09-05T09:42:52.067Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml308beta # [94552.086923] beta systemd[1]: Finished Permit User Sessions.309beta # [94552.088373] beta systemd[1]: Started Console Getty.310beta # [94552.088497] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0311beta # [94552.088635] beta systemd[1]: Reached target Login Prompts.312beta # [94552.099326] beta data-mesher[210]: time=2026-09-05T09:42:52.085Z level=INFO msg="checking file integrity"313beta # [94552.099426] beta data-mesher[210]: time=2026-09-05T09:42:52.085Z level=INFO msg="file integrity check complete"314beta # [94552.103351] beta data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="libp2p host created" peer_id=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y 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]"315beta # [94552.103384] beta data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="registered HTTP route" method=GET path=/files316beta # [94552.103384] beta data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name317beta # [94552.103421] beta data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name318beta # [94552.103421] beta data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="starting server"319beta # [94552.103917] beta data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="HTTP server listening" address=[::1]:7331320beta # [94552.103972] beta data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331321beta # [94552.104594] beta data-mesher[210]: time=2026-09-05T09:42:52.090Z level=INFO msg="waiting for DHT to populate" delay=10s322beta # [94552.110016] beta data-mesher[210]: time=2026-09-05T09:42:52.095Z level=INFO msg="peer connected" peer_id=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe remote_addr=/ip4/192.168.1.3/tcp/7946323beta # [94552.147153] beta data-mesher[210]: time=2026-09-05T09:42:52.132Z level=INFO msg="peer connected" peer_id=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ remote_addr=/ip4/192.168.1.1/tcp/7946324alpha # [94552.135276] alpha data-mesher[211]: time=2026-09-05T09:42:52.121Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]325alpha # [94552.136373] alpha data-mesher[211]: time=2026-09-05T09:42:52.122Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ: [/dns/alpha.clan/tcp/7946]} {12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ326alpha # [94552.136429] alpha data-mesher[211]: time=2026-09-05T09:42:52.122Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml327alpha # [94552.137722] alpha data-mesher[211]: time=2026-09-05T09:42:52.123Z level=INFO msg="checking file integrity"328alpha # [94552.137838] alpha data-mesher[211]: time=2026-09-05T09:42:52.123Z level=INFO msg="file integrity check complete"329alpha # [94552.141735] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="libp2p host created" peer_id=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ 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]"330alpha # [94552.141772] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="registered HTTP route" method=GET path=/files331alpha # [94552.141772] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name332alpha # [94552.141772] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name333alpha # [94552.141822] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="starting server"334alpha # [94552.141880] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="waiting for DHT to populate" delay=10s335alpha # [94552.142018] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="HTTP server listening" address=[::1]:7331336alpha # [94552.142068] alpha data-mesher[211]: time=2026-09-05T09:42:52.127Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331337alpha # [94552.146474] alpha data-mesher[211]: time=2026-09-05T09:42:52.132Z level=INFO msg="peer connected" peer_id=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y remote_addr=/ip4/192.168.1.2/tcp/7946338alpha # [94552.154098] alpha data-mesher[211]: time=2026-09-05T09:42:52.139Z level=INFO msg="peer connected" peer_id=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe remote_addr=/ip4/192.168.1.3/tcp/7946339alpha # [94552.231435] alpha systemd-logind[227]: New seat seat0.340alpha # [94552.231612] alpha systemd[1]: Started User Login Management.341alpha # [94552.233600] alpha systemd[1]: Starting linger-users.service...342alpha # [94552.246643] alpha systemd[1]: linger-users.service: Deactivated successfully.343alpha # [94552.246775] alpha systemd[1]: Finished linger-users.service.344gamma # [94552.084910] gamma data-mesher[210]: time=2026-09-05T09:42:52.070Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]345gamma # [94552.085975] gamma data-mesher[210]: time=2026-09-05T09:42:52.071Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ: [/dns/alpha.clan/tcp/7946]} {12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe346gamma # [94552.085975] gamma data-mesher[210]: time=2026-09-05T09:42:52.071Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml347gamma # [94552.099545] gamma data-mesher[210]: time=2026-09-05T09:42:52.085Z level=INFO msg="checking file integrity"348gamma # [94552.099664] gamma data-mesher[210]: time=2026-09-05T09:42:52.085Z level=INFO msg="file integrity check complete"349gamma # [94552.103448] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="libp2p host created" peer_id=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe 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]"350gamma # [94552.103495] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="registered HTTP route" method=GET path=/files351gamma # [94552.103495] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name352gamma # [94552.103495] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name353gamma # [94552.103495] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="starting server"354gamma # [94552.103600] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="waiting for DHT to populate" delay=10s355gamma # [94552.103694] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="HTTP server listening" address=[::1]:7331356gamma # [94552.103741] gamma data-mesher[210]: time=2026-09-05T09:42:52.089Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331357gamma # [94552.109247] gamma data-mesher[210]: time=2026-09-05T09:42:52.094Z level=INFO msg="peer connected" peer_id=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y remote_addr=/ip4/192.168.1.2/tcp/7946358gamma # [94552.156102] gamma data-mesher[210]: time=2026-09-05T09:42:52.141Z level=INFO msg="peer connected" peer_id=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ remote_addr=/ip4/192.168.1.1/tcp/7946359gamma # [94552.229715] gamma systemd-logind[228]: New seat seat0.360gamma # [94552.229906] gamma systemd[1]: Started User Login Management.361gamma # [94552.231996] gamma systemd[1]: Starting linger-users.service...362gamma # [94552.245600] gamma systemd[1]: linger-users.service: Deactivated successfully.363gamma # [94552.245921] gamma systemd[1]: Finished linger-users.service.364beta # [94552.445036] beta systemd-logind[232]: New seat seat0.365beta # [94552.445246] beta systemd[1]: Started User Login Management.366beta # [94552.446440] beta systemd[1]: Starting linger-users.service...367beta # [94552.493436] beta systemd[1]: linger-users.service: Deactivated successfully.368beta # [94552.493728] beta systemd[1]: Finished linger-users.service.369beta # [94552.772098] beta systemd-networkd[205]: eth1: Gained IPv6LL370alpha # [94553.088222] alpha systemd-networkd[206]: eth1: Gained IPv6LL371gamma # [94553.056177] gamma systemd-networkd[205]: eth1: Gained IPv6LL372alpha: still waiting for container 'alpha' to reach ready state...373alpha # [94562.104710] alpha data-mesher[211]: time=2026-09-05T09:43:02.090Z level=INFO msg="received state sync from peer" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe374beta # [94562.105535] beta data-mesher[210]: time=2026-09-05T09:43:02.091Z level=INFO msg="performing state exchange with peers on join" count=1375alpha # [94562.104710] alpha data-mesher[211]: time=2026-09-05T09:43:02.090Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe376beta # [94562.105535] beta data-mesher[210]: time=2026-09-05T09:43:02.091Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s377alpha # [94562.142376] alpha data-mesher[211]: time=2026-09-05T09:43:02.128Z level=INFO msg="performing state exchange with peers on join" count=1378beta # [94562.106051] beta data-mesher[210]: time=2026-09-05T09:43:02.091Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe379beta # [94562.106051] beta data-mesher[210]: time=2026-09-05T09:43:02.091Z level=INFO msg="state exchange complete" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s380beta # [94562.106105] beta data-mesher[210]: time=2026-09-05T09:43:02.091Z level=INFO msg="server started"381beta # [94562.106164] beta data-mesher[210]: time=2026-09-05T09:43:02.091Z level=INFO msg="starting expired-file sweeper" interval=1m0s382alpha # [94562.142496] alpha data-mesher[211]: time=2026-09-05T09:43:02.128Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s383beta # [94562.106253] beta systemd[1]: Started data mesher daemon.384alpha # [94562.142957] alpha data-mesher[211]: time=2026-09-05T09:43:02.128Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe385beta # [94562.106478] beta systemd[1]: Reached target Multi-User System.386alpha # [94562.142957] alpha data-mesher[211]: time=2026-09-05T09:43:02.128Z level=INFO msg="state exchange complete" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s387beta # [94562.106799] beta systemd[1]: Startup finished in 11.590s.388alpha # [94562.143022] alpha data-mesher[211]: time=2026-09-05T09:43:02.128Z level=INFO msg="server started"389alpha # [94562.143569] alpha data-mesher[211]: time=2026-09-05T09:43:02.128Z level=INFO msg="starting expired-file sweeper" interval=1m0s390alpha # [94562.143197] alpha systemd[1]: Started data mesher daemon.391alpha # [94562.143445] alpha systemd[1]: Reached target Multi-User System.392alpha # [94562.143950] alpha systemd[1]: Startup finished in 11.641s.393gamma # [94562.104150] gamma data-mesher[210]: time=2026-09-05T09:43:02.089Z level=INFO msg="performing state exchange with peers on join" count=1394gamma # [94562.104505] gamma data-mesher[210]: time=2026-09-05T09:43:02.090Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s395gamma # [94562.104886] gamma data-mesher[210]: time=2026-09-05T09:43:02.090Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ396gamma # [94562.104886] gamma data-mesher[210]: time=2026-09-05T09:43:02.090Z level=INFO msg="state exchange complete" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s397gamma # [94562.104940] gamma data-mesher[210]: time=2026-09-05T09:43:02.090Z level=INFO msg="server started"398gamma # [94562.104996] gamma data-mesher[210]: time=2026-09-05T09:43:02.090Z level=INFO msg="starting expired-file sweeper" interval=1m0s399gamma # [94562.105214] gamma systemd[1]: Started data mesher daemon.400gamma # [94562.105741] gamma systemd[1]: Reached target Multi-User System.401gamma # [94562.105936] gamma data-mesher[210]: time=2026-09-05T09:43:02.091Z level=INFO msg="received state sync from peer" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y402gamma # [94562.105936] gamma data-mesher[210]: time=2026-09-05T09:43:02.091Z level=INFO msg="merging remote state" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y403gamma # [94562.106184] gamma systemd[1]: Startup finished in 11.602s.404gamma # [94562.142846] gamma data-mesher[210]: time=2026-09-05T09:43:02.128Z level=INFO msg="received state sync from peer" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ405gamma # [94562.142846] gamma data-mesher[210]: time=2026-09-05T09:43:02.128Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ406alpha: (finished: waiting for unit data-mesher.service, in 12.67 seconds)407beta: waiting for unit data-mesher.service408beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)409gamma: waiting for unit data-mesher.service410gamma: (finished: waiting for unit data-mesher.service, in 0.01 seconds)411alpha: must succeed: echo -n 'hello world' > /tmp/test_file412alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)413alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key414alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)415??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.416 File "/nix/store/cag5izn0mcnmyi82zb3rwfs1s1afa6q5-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39417alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file418??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.419 File "/nix/store/cag5izn0mcnmyi82zb3rwfs1s1afa6q5-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39420alpha: (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)421beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file422alpha # [94562.703922] alpha data-mesher[211]: time=2026-09-05T09:43:02.689Z level=INFO msg=http_request uri=/files/test_file status=204423alpha # [94567.107445] alpha data-mesher[211]: time=2026-09-05T09:43:07.093Z level=INFO msg="received state sync from peer" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y424alpha # [94567.107445] alpha data-mesher[211]: time=2026-09-05T09:43:07.093Z level=INFO msg="merging remote state" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y425alpha # [94567.108392] alpha data-mesher[211]: time=2026-09-05T09:43:07.094Z level=INFO msg="received file request" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_file426alpha # [94567.110262] alpha data-mesher[211]: time=2026-09-05T09:43:07.096Z level=INFO msg="file transfer complete" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_file427alpha # [94567.143761] alpha data-mesher[211]: time=2026-09-05T09:43:07.129Z level=DEBUG msg="attempting push/pull" peer_count=2428alpha # [94567.143848] alpha data-mesher[211]: time=2026-09-05T09:43:07.129Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s429alpha # [94567.144646] alpha data-mesher[211]: time=2026-09-05T09:43:07.130Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe430alpha # [94567.144646] alpha data-mesher[211]: time=2026-09-05T09:43:07.130Z level=INFO msg="state exchange complete" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s431alpha # [94567.144776] alpha data-mesher[211]: time=2026-09-05T09:43:07.130Z level=DEBUG msg="push/pull successful" interval=5s432alpha # [94567.144976] alpha data-mesher[211]: time=2026-09-05T09:43:07.130Z level=INFO msg="received file request" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_file433alpha # [94567.145912] alpha data-mesher[211]: time=2026-09-05T09:43:07.131Z level=INFO msg="file transfer complete" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_file434gamma # [94567.105219] gamma data-mesher[210]: time=2026-09-05T09:43:07.091Z level=DEBUG msg="attempting push/pull" peer_count=2435gamma # [94567.105219] gamma data-mesher[210]: time=2026-09-05T09:43:07.091Z level=DEBUG msg="initiating state exchange" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y timeout=5s436gamma # [94567.105816] gamma data-mesher[210]: time=2026-09-05T09:43:07.091Z level=INFO msg="merging remote state" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y437gamma # [94567.105816] gamma data-mesher[210]: time=2026-09-05T09:43:07.091Z level=INFO msg="state exchange complete" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y timeout=5s438gamma # [94567.105880] gamma data-mesher[210]: time=2026-09-05T09:43:07.091Z level=DEBUG msg="push/pull successful" interval=5s439gamma # [94567.144447] gamma data-mesher[210]: time=2026-09-05T09:43:07.130Z level=INFO msg="received state sync from peer" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ440gamma # [94567.144447] gamma data-mesher[210]: time=2026-09-05T09:43:07.130Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ441gamma # [94567.144561] gamma data-mesher[210]: time=2026-09-05T09:43:07.130Z level=DEBUG msg="new file detected" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ name=test_file name=test_file442gamma # [94567.144561] gamma data-mesher[210]: time=2026-09-05T09:43:07.130Z level=INFO msg="scheduling file download" name=test_file443gamma # [94567.144605] gamma data-mesher[210]: time=2026-09-05T09:43:07.130Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-05 09:43:02.686 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ444gamma # [94567.147481] gamma data-mesher[210]: time=2026-09-05T09:43:07.133Z level=INFO msg="download complete" name=test_file signed_at="2026-09-05 09:43:02.686 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ written=true elapsed=2.898519ms445beta # [94567.105675] beta data-mesher[210]: time=2026-09-05T09:43:07.091Z level=INFO msg="received state sync from peer" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe446beta # [94567.105675] beta data-mesher[210]: time=2026-09-05T09:43:07.091Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe447beta # [94567.107069] beta data-mesher[210]: time=2026-09-05T09:43:07.092Z level=DEBUG msg="attempting push/pull" peer_count=2448beta # [94567.107069] beta data-mesher[210]: time=2026-09-05T09:43:07.092Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s449beta # [94567.107833] beta data-mesher[210]: time=2026-09-05T09:43:07.093Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ450beta # [94567.107904] beta data-mesher[210]: time=2026-09-05T09:43:07.093Z level=DEBUG msg="new file detected" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ name=test_file name=test_file451beta # [94567.107904] beta data-mesher[210]: time=2026-09-05T09:43:07.093Z level=INFO msg="state exchange complete" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s452beta # [94567.108034] beta data-mesher[210]: time=2026-09-05T09:43:07.093Z level=INFO msg="scheduling file download" name=test_file453beta # [94567.108034] beta data-mesher[210]: time=2026-09-05T09:43:07.093Z level=DEBUG msg="push/pull successful" interval=5s454beta # [94567.108034] beta data-mesher[210]: time=2026-09-05T09:43:07.093Z level=INFO msg="downloading file" name=test_file signed_at="2026-09-05 09:43:02.686 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ455beta # [94567.122644] beta data-mesher[210]: time=2026-09-05T09:43:07.108Z level=INFO msg="download complete" name=test_file signed_at="2026-09-05 09:43:02.686 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ written=true elapsed=14.656602ms456beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)457gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file458gamma: (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)459beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key460beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.04 seconds)461alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file462beta # [94567.800434] beta data-mesher[210]: time=2026-09-05T09:43:07.786Z level=INFO msg=http_request uri=/files/test_file status=204463alpha # [94572.108730] alpha data-mesher[211]: time=2026-09-05T09:43:12.094Z level=INFO msg="received state sync from peer" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y464alpha # [94572.108730] alpha data-mesher[211]: time=2026-09-05T09:43:12.094Z level=INFO msg="merging remote state" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y465alpha # [94572.110595] alpha data-mesher[211]: time=2026-09-05T09:43:12.096Z level=DEBUG msg="imported tombstone" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y name=test_file written=true466alpha # [94572.145766] alpha data-mesher[211]: time=2026-09-05T09:43:12.131Z level=DEBUG msg="attempting push/pull" peer_count=2467alpha # [94572.145766] alpha data-mesher[211]: time=2026-09-05T09:43:12.131Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s468alpha # [94572.146534] alpha data-mesher[211]: time=2026-09-05T09:43:12.132Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe469alpha # [94572.146583] alpha data-mesher[211]: time=2026-09-05T09:43:12.132Z level=INFO msg="state exchange complete" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s470alpha # [94572.146583] alpha data-mesher[211]: time=2026-09-05T09:43:12.132Z level=DEBUG msg="push/pull successful" interval=5s471beta # [94572.107229] beta data-mesher[210]: time=2026-09-05T09:43:12.093Z level=INFO msg="received state sync from peer" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe472beta # [94572.107229] beta data-mesher[210]: time=2026-09-05T09:43:12.093Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe473gamma # [94572.106510] gamma data-mesher[210]: time=2026-09-05T09:43:12.092Z level=DEBUG msg="attempting push/pull" peer_count=2474beta # [94572.108150] beta data-mesher[210]: time=2026-09-05T09:43:12.093Z level=DEBUG msg="attempting push/pull" peer_count=2475gamma # [94572.106871] gamma data-mesher[210]: time=2026-09-05T09:43:12.092Z level=DEBUG msg="initiating state exchange" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y timeout=5s476beta # [94572.108221] beta data-mesher[210]: time=2026-09-05T09:43:12.094Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s477gamma # [94572.107562] gamma data-mesher[210]: time=2026-09-05T09:43:12.093Z level=INFO msg="merging remote state" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y478beta # [94572.110982] beta data-mesher[210]: time=2026-09-05T09:43:12.096Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ479gamma # [94572.110406] gamma data-mesher[210]: time=2026-09-05T09:43:12.096Z level=DEBUG msg="imported tombstone" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y name=test_file written=true480beta # [94572.111046] beta data-mesher[210]: time=2026-09-05T09:43:12.096Z level=INFO msg="state exchange complete" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s481gamma # [94572.110406] gamma data-mesher[210]: time=2026-09-05T09:43:12.096Z level=INFO msg="state exchange complete" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y timeout=5s482gamma # [94572.110536] gamma data-mesher[210]: time=2026-09-05T09:43:12.096Z level=DEBUG msg="push/pull successful" interval=5s483gamma # [94572.146246] gamma data-mesher[210]: time=2026-09-05T09:43:12.132Z level=INFO msg="received state sync from peer" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ484gamma # [94572.146246] gamma data-mesher[210]: time=2026-09-05T09:43:12.132Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ485beta # [94572.111046] beta data-mesher[210]: time=2026-09-05T09:43:12.096Z level=DEBUG msg="push/pull successful" interval=5s486alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)487beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file488beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)489gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file490gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)491alpha: must succeed: cat /nix/store/jvpsqchyzp9dnxnslxqbfbbnb2pginry-per-machine-alpha-data-mesher-node-identity_identity.pub492alpha: (finished: must succeed: cat /nix/store/jvpsqchyzp9dnxnslxqbfbbnb2pginry-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)493alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file494alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)495alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert496alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)497alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns498alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns, in 0.01 seconds)499beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns500alpha # [94572.904782] alpha data-mesher[211]: time=2026-09-05T09:43:12.890Z level=INFO msg=http_request uri=/files/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns status=204501alpha # [94577.112876] alpha data-mesher[211]: time=2026-09-05T09:43:17.098Z level=INFO msg="received state sync from peer" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y502alpha # [94577.112876] alpha data-mesher[211]: time=2026-09-05T09:43:17.098Z level=INFO msg="merging remote state" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y503alpha # [94577.114301] alpha data-mesher[211]: time=2026-09-05T09:43:17.100Z level=INFO msg="received file request" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns504alpha # [94577.116568] alpha data-mesher[211]: time=2026-09-05T09:43:17.102Z level=INFO msg="file transfer complete" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns505alpha # [94577.146734] alpha data-mesher[211]: time=2026-09-05T09:43:17.132Z level=DEBUG msg="attempting push/pull" peer_count=2506alpha # [94577.146864] alpha data-mesher[211]: time=2026-09-05T09:43:17.132Z level=DEBUG msg="initiating state exchange" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s507alpha # [94577.148489] alpha data-mesher[211]: time=2026-09-05T09:43:17.134Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe508alpha # [94577.148599] alpha data-mesher[211]: time=2026-09-05T09:43:17.134Z level=INFO msg="state exchange complete" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe timeout=5s509alpha # [94577.148599] alpha data-mesher[211]: time=2026-09-05T09:43:17.134Z level=DEBUG msg="push/pull successful" interval=5s510alpha # [94577.148978] alpha data-mesher[211]: time=2026-09-05T09:43:17.134Z level=INFO msg="received file request" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns511alpha # [94577.149767] alpha data-mesher[211]: time=2026-09-05T09:43:17.135Z level=INFO msg="file transfer complete" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe network="DyZh+TqFB/c80ScY5LOP6EyCC6x204T9h2e8K0wBn5Q=" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns512gamma # [94577.111526] gamma data-mesher[210]: time=2026-09-05T09:43:17.097Z level=DEBUG msg="attempting push/pull" peer_count=2513gamma # [94577.111526] gamma data-mesher[210]: time=2026-09-05T09:43:17.097Z level=DEBUG msg="initiating state exchange" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y timeout=5s514gamma # [94577.112676] gamma data-mesher[210]: time=2026-09-05T09:43:17.098Z level=INFO msg="merging remote state" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y515gamma # [94577.112752] gamma data-mesher[210]: time=2026-09-05T09:43:17.098Z level=INFO msg="state exchange complete" peer=12D3KooWA7Un1wjrPbF8BrZpUW6wbCJrAzRYPHM9NSLv1BDmyR2Y timeout=5s516gamma # [94577.112752] gamma data-mesher[210]: time=2026-09-05T09:43:17.098Z level=DEBUG msg="push/pull successful" interval=5s517gamma # [94577.147503] gamma data-mesher[210]: time=2026-09-05T09:43:17.133Z level=INFO msg="received state sync from peer" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ518gamma # [94577.147648] gamma data-mesher[210]: time=2026-09-05T09:43:17.133Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ519gamma # [94577.148196] gamma data-mesher[210]: time=2026-09-05T09:43:17.134Z level=DEBUG msg="new file detected" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ name=test_file name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns520gamma # [94577.148355] gamma data-mesher[210]: time=2026-09-05T09:43:17.134Z level=INFO msg="scheduling file download" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns521gamma # [94577.148452] gamma data-mesher[210]: time=2026-09-05T09:43:17.134Z level=INFO msg="downloading file" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns signed_at="2026-09-05 09:43:12.888 +0000 UTC" signed_by="GW8gZN1BVQLQAxGei7e/N3w0LjXJEDwiacBzkl6v6ns=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ522gamma # [94577.151308] gamma data-mesher[210]: time=2026-09-05T09:43:17.137Z level=INFO msg="download complete" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns signed_at="2026-09-05 09:43:12.888 +0000 UTC" signed_by="GW8gZN1BVQLQAxGei7e/N3w0LjXJEDwiacBzkl6v6ns=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ written=true elapsed=2.9062ms523beta # [94577.111739] beta data-mesher[210]: time=2026-09-05T09:43:17.097Z level=DEBUG msg="attempting push/pull" peer_count=2524beta # [94577.111739] beta data-mesher[210]: time=2026-09-05T09:43:17.097Z level=DEBUG msg="initiating state exchange" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s525beta # [94577.112582] beta data-mesher[210]: time=2026-09-05T09:43:17.098Z level=INFO msg="received state sync from peer" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe526beta # [94577.112582] beta data-mesher[210]: time=2026-09-05T09:43:17.098Z level=INFO msg="merging remote state" peer=12D3KooWJs4a9N4rSeHLbXCTMSZi2u2iXNAsW9GaQcJyaGhfJdhe527beta # [94577.113273] beta data-mesher[210]: time=2026-09-05T09:43:17.099Z level=INFO msg="merging remote state" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ528beta # [94577.113651] beta data-mesher[210]: time=2026-09-05T09:43:17.099Z level=DEBUG msg="new file detected" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ name=test_file name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns529beta # [94577.113651] beta data-mesher[210]: time=2026-09-05T09:43:17.099Z level=INFO msg="state exchange complete" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ timeout=5s530beta # [94577.113778] beta data-mesher[210]: time=2026-09-05T09:43:17.099Z level=DEBUG msg="push/pull successful" interval=5s531beta # [94577.113778] beta data-mesher[210]: time=2026-09-05T09:43:17.099Z level=INFO msg="scheduling file download" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns532beta # [94577.113778] beta data-mesher[210]: time=2026-09-05T09:43:17.099Z level=INFO msg="downloading file" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns signed_at="2026-09-05 09:43:12.888 +0000 UTC" signed_by="GW8gZN1BVQLQAxGei7e/N3w0LjXJEDwiacBzkl6v6ns=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ533beta # [94577.118163] beta data-mesher[210]: time=2026-09-05T09:43:17.103Z level=INFO msg="download complete" name=test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns signed_at="2026-09-05 09:43:12.888 +0000 UTC" signed_by="GW8gZN1BVQLQAxGei7e/N3w0LjXJEDwiacBzkl6v6ns=" peer=12D3KooWBXeeCNhu9XNj6uJx9oJQaDJivtfKoPAXt9wKqSEM5yiJ written=true elapsed=4.38694ms534beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns, in 5.05 seconds)535gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns536gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns, in 0.01 seconds)537alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert538alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)539alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub540Error: failed to update file: 403 Forbidden, signer GW8gZN1BVQLQAxGei7e/N3w0LjXJEDwiacBzkl6v6ns= is not authorized for this file test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns541alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/h9a92cywhmavxnqkg8mmbfpn5gkb3lgl-shared-data-mesher-network_network.pub, in 0.03 seconds)542(finished: run the VM test script, in 28.04 seconds)543alpha # [94578.010027] alpha data-mesher[211]: time=2026-09-05T09:43:17.995Z level=INFO msg=http_request uri=/files/test_ns/GW8gZN1BVQLQAxGei7e_N3w0LjXJEDwiacBzkl6v6ns status=403544test script finished in 30.00s545cleanup546kill NspawnMachine (pid 53)547kill NspawnMachine (pid 54)548549Traceback (most recent call last):550 File "/nix/store/bb3iw9xkbhd3l5i277i0w599xgl72knz-run-nspawn-1.0/lib/python3.14/site-packages/run_nspawn/__init__.py", line 218, in run551 exit_code = cp.wait()552 File "/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/lib/python3.14/subprocess.py", line 1279, in wait553 return self._wait(timeout=timeout)554 ~~~~~~~~~~^^^^^^^^^^^^^^^^^555 File "/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/lib/python3.14/subprocess.py", line 2084, in _wait556 (pid, sts) = self._try_wait(0)557 ~~~~~~~~~~~~~~^^^558 File "/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/lib/python3.14/subprocess.py", line 2042, in _try_wait559 (pid, sts) = os.waitpid(self.pid, wait_flags)560 ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^561 File "/nix/store/bb3iw9xkbhd3l5i277i0w599xgl72knz-run-nspawn-1.0/lib/python3.14/site-packages/run_nspawn/__init__.py", line 17, in <lambda>562 signal.signal(signal.SIGTERM, lambda _signum, _frame: sys.exit(0))563 ~~~~~~~~^^^564SystemExit: 0565566During handling of the above exception, another exception occurred:567568Traceback (most recent call last):569 File "/nix/store/bb3iw9xkbhd3l5i277i0w599xgl72knz-run-nspawn-1.0/bin/.run-nspawn-wrapped", line 9, in <module>570 sys.exit(main())571 ~~~~^^572 File "/nix/store/bb3iw9xkbhd3l5i277i0w599xgl72knz-run-nspawn-1.0/lib/python3.14/site-packages/run_nspawn/__init__.py", line 280, in main573 run(574 ~~~^575 container_name=args.container_name,576 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^577 ...<5 lines>...578 cmdline=args.cmdline,579 ^^^^^^^^^^^^^^^^^^^^^580 )581 ^582 File "/nix/store/bb3iw9xkbhd3l5i277i0w599xgl72knz-run-nspawn-1.0/lib/python3.14/site-packages/run_nspawn/__init__.py", line 232, in run583 subprocess.run(584 ~~~~~~~~~~~~~~^585 [586 ^587 ...<4 lines>...588 check=True,589 ^^^^^^^^^^^590 )591 ^592 File "/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/lib/python3.14/subprocess.py", line 578, in run593 raise CalledProcessError(retcode, process.args,594 output=stdout, stderr=stderr)595subprocess.CalledProcessError: Command '['/nix/store/g02rikjs4v596794gar5hv251p14d16q-e2fsprogs-1.47.4-bin/bin/chattr', '-i', PosixPath('/build/vm-state-beta/var/empty')]' returned non-zero exit status 1.596kill NspawnMachine (pid 55)597Container alpha terminated by signal KILL.598Container beta terminated by signal KILL.599Container gamma terminated by signal KILL.600(finished: cleanup, in 0.49 seconds)

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

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