container-test-run-data-mesher log

succeeded `container-test-run-data-mesher` `checks.aarch64-linux.data-mesher` · build #462 · 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)13alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...14beta: systemd-nspawn running (pid 55)15gamma: systemd-nspawn running (pid 57)16beta: Waiting for journal at /build/vm-state-beta/var/log/journal...17gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...18(finished: start all VMs, in 0.00 seconds)19alpha: waiting for unit data-mesher.service20nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE21nixos-nspawn(gamma): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.22nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE23nixos-nspawn(beta): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.24nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE25nixos-nspawn(alpha): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths.26Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.27░ Spawning container gamma on /build/vm-state-gamma.28Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.29░ Spawning container beta on /build/vm-state-beta.30Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file.31░ Spawning container alpha on /build/vm-state-alpha.32alpha # [6500626.503131] alpha systemd-journald[87]: Journal started33alpha # [6500626.503179] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/38bda7381ce14d928ea15932185926d8) is 8M, max 2.5G, 2.4G free.34alpha # [6500626.504649] alpha systemd[1]: Finished Apply Kernel Variables.35alpha # [6500626.511186] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.36alpha # [6500626.518955] alpha systemd[1]: Starting Flush Journal to Persistent Storage...37alpha # [6500626.519789] alpha systemd[1]: Starting Network Name Resolution...38alpha # [6500626.520530] alpha systemd[1]: Starting Create Static Device Nodes in /dev...39beta # [6500626.523991] beta systemd-journald[87]: Journal started40alpha # [6500626.527665] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/38bda7381ce14d928ea15932185926d8 is 1.431ms for 7 entries.41beta # [6500626.524056] beta systemd-journald[87]: Runtime Journal (/run/log/journal/6f28f8b9b51e4bb5b64a8c03e974eb92) is 8M, max 2.5G, 2.4G free.42beta # [6500626.527181] beta systemd[1]: Finished Create Static Device Nodes in /dev gracefully.43alpha # [6500626.527665] alpha systemd-journald[87]: System Journal (/var/log/journal/38bda7381ce14d928ea15932185926d8) is 8M, max 4G, 3.9G free.44alpha # [6500626.533614] alpha systemd[1]: Finished Create Static Device Nodes in /dev.45alpha # [6500626.533845] alpha systemd[1]: Reached target Preparation for Local File Systems.46beta # [6500626.541175] beta systemd[1]: Starting Flush Journal to Persistent Storage...47alpha # [6500626.533933] alpha systemd[1]: Reached target Local File Systems.48beta # [6500626.542026] beta systemd[1]: Starting Network Name Resolution...49alpha # [6500626.534665] alpha systemd[1]: Listening on Boot Loader Control Service Socket.50beta # [6500626.542735] beta systemd[1]: Starting Create Static Device Nodes in /dev...51gamma # [6500626.502633] gamma systemd-journald[87]: Journal started52beta # [6500626.550346] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/6f28f8b9b51e4bb5b64a8c03e974eb92 is 1.504ms for 6 entries.53gamma # [6500626.502685] gamma systemd-journald[87]: Runtime Journal (/run/log/journal/d111737086474b52bfb962378708e1f7) is 8M, max 2.5G, 2.4G free.54beta # [6500626.550346] beta systemd-journald[87]: System Journal (/var/log/journal/6f28f8b9b51e4bb5b64a8c03e974eb92) is 8M, max 4G, 3.9G free.55gamma # [6500626.504692] gamma systemd[1]: Finished Apply Kernel Variables.56beta # [6500626.555530] beta systemd[1]: Finished Create Static Device Nodes in /dev.57gamma # [6500626.511253] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.58beta # [6500626.555764] beta systemd[1]: Reached target Preparation for Local File Systems.59gamma # [6500626.519075] gamma systemd[1]: Starting Flush Journal to Persistent Storage...60beta # [6500626.555848] beta systemd[1]: Reached target Local File Systems.61gamma # [6500626.519984] gamma systemd[1]: Starting Network Name Resolution...62beta # [6500626.556588] beta systemd[1]: Listening on Boot Loader Control Service Socket.63gamma # [6500626.520709] gamma systemd[1]: Starting Create Static Device Nodes in /dev...64beta # [6500626.556631] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container65gamma # [6500626.528193] gamma systemd-journald[87]: Time spent on flushing to /var/log/journal/d111737086474b52bfb962378708e1f7 is 1.326ms for 7 entries.66beta # [6500626.557410] beta systemd[1]: Starting Save Transient machine-id to Disk...67gamma # [6500626.528193] gamma systemd-journald[87]: System Journal (/var/log/journal/d111737086474b52bfb962378708e1f7) is 8M, max 4G, 3.9G free.68beta # [6500626.557447] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys69alpha # [6500626.534706] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container70gamma # [6500626.535523] gamma systemd[1]: Finished Create Static Device Nodes in /dev.71gamma # [6500626.536164] gamma systemd[1]: Reached target Preparation for Local File Systems.72alpha # [6500626.535582] alpha systemd[1]: Starting Save Transient machine-id to Disk...73gamma # [6500626.536270] gamma systemd[1]: Reached target Local File Systems.74alpha # [6500626.535620] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys75gamma # [6500626.537035] gamma systemd[1]: Listening on Boot Loader Control Service Socket.76alpha # [6500626.558801] alpha systemd[1]: Finished Flush Journal to Persistent Storage.77gamma # [6500626.537074] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container78alpha # [6500626.560407] alpha systemd[1]: Starting Create System Files and Directories...79gamma # [6500626.537845] gamma systemd[1]: Starting Save Transient machine-id to Disk...80alpha # [6500626.574414] alpha systemd-tmpfiles[144]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted81gamma # [6500626.537877] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys82alpha # [6500626.574590] alpha systemd-tmpfiles[144]: fchmod() of /var/log/journal failed: Operation not permitted83gamma # [6500626.558862] gamma systemd[1]: Finished Flush Journal to Persistent Storage.84alpha # [6500626.574715] alpha systemd-tmpfiles[144]: fchmod() of /var/log/journal/38bda7381ce14d928ea15932185926d8 failed: Operation not permitted85gamma # [6500626.560322] gamma systemd[1]: Starting Create System Files and Directories...86alpha # [6500626.574896] alpha systemd-tmpfiles[144]: fchmod() of /run/log/journal failed: Operation not permitted87gamma # [6500626.574638] gamma systemd-tmpfiles[145]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted88alpha # [6500626.576309] alpha systemd[1]: Finished Create System Files and Directories.89gamma # [6500626.574806] gamma systemd-tmpfiles[145]: fchmod() of /var/log/journal failed: Operation not permitted90alpha # [6500626.577355] alpha systemd[1]: Starting Rebuild Journal Catalog...91gamma # [6500626.574923] gamma systemd-tmpfiles[145]: fchmod() of /var/log/journal/d111737086474b52bfb962378708e1f7 failed: Operation not permitted92alpha # [6500626.578199] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...93gamma # [6500626.575109] gamma systemd-tmpfiles[145]: fchmod() of /run/log/journal failed: Operation not permitted94alpha # [6500626.590182] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.95gamma # [6500626.576457] gamma systemd[1]: Finished Create System Files and Directories.96alpha # [6500626.596650] alpha systemd[1]: Finished Rebuild Journal Catalog.97gamma # [6500626.577418] gamma systemd[1]: Starting Rebuild Journal Catalog...98alpha # [6500626.598323] alpha systemd[1]: Starting Update is Completed...99gamma # [6500626.578221] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...100alpha # [6500626.608645] alpha systemd[1]: Finished Update is Completed.101gamma # [6500626.589742] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.102alpha # [6500626.623887] alpha systemd[1]: Finished Save Transient machine-id to Disk.103gamma # [6500626.596659] gamma systemd[1]: Finished Rebuild Journal Catalog.104alpha # [6500626.650822] alpha systemd[1]: Finished Firewall.105gamma # [6500626.598285] gamma systemd[1]: Starting Update is Completed...106alpha # [6500626.650936] alpha systemd[1]: Reached target Preparation for Network.107gamma # [6500626.607739] gamma systemd[1]: Finished Update is Completed.108alpha # [6500626.651157] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.109gamma # [6500626.625679] gamma systemd[1]: Finished Save Transient machine-id to Disk.110alpha # [6500626.652212] alpha systemd[1]: Starting Network Management...111gamma # [6500626.653385] gamma systemd[1]: Finished Firewall.112gamma # [6500626.653528] gamma systemd[1]: Reached target Preparation for Network.113gamma # [6500626.653767] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.114beta # [6500626.571165] beta systemd[1]: Finished Flush Journal to Persistent Storage.115beta # [6500626.572509] beta systemd[1]: Starting Create System Files and Directories...116gamma # [6500626.654724] gamma systemd[1]: Starting Network Management...117beta # [6500626.586394] beta systemd-tmpfiles[138]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted118beta # [6500626.586559] beta systemd-tmpfiles[138]: fchmod() of /var/log/journal failed: Operation not permitted119beta # [6500626.586673] beta systemd-tmpfiles[138]: fchmod() of /var/log/journal/6f28f8b9b51e4bb5b64a8c03e974eb92 failed: Operation not permitted120beta # [6500626.586857] beta systemd-tmpfiles[138]: fchmod() of /run/log/journal failed: Operation not permitted121beta # [6500626.588272] beta systemd[1]: Finished Create System Files and Directories.122beta # [6500626.589246] beta systemd[1]: Starting Rebuild Journal Catalog...123beta # [6500626.589885] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...124beta # [6500626.602601] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.125beta # [6500626.608811] beta systemd[1]: Finished Rebuild Journal Catalog.126beta # [6500626.609964] beta systemd[1]: Starting Update is Completed...127beta # [6500626.619553] beta systemd[1]: Finished Update is Completed.128beta # [6500626.625721] beta systemd[1]: Finished Save Transient machine-id to Disk.129beta # [6500626.674711] beta systemd[1]: Finished Firewall.130beta # [6500626.674869] beta systemd[1]: Reached target Preparation for Network.131beta # [6500626.675102] beta systemd[1]: Listening on Network Management Resolve Hook Socket.132beta # [6500626.676192] beta systemd[1]: Starting Network Management...133gamma # [6500627.194077] gamma systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted134gamma # [6500627.194237] gamma systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted135gamma # [6500627.202240] 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.136gamma # [6500627.202410] 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.137gamma # [6500627.202584] gamma systemd-networkd[205]: lo: Link UP138gamma # [6500627.202587] gamma systemd-networkd[205]: lo: Gained carrier139gamma # [6500627.203039] gamma systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.140gamma # [6500627.203504] gamma systemd[1]: Started Network Management.141gamma # [6500627.204118] gamma systemd-networkd[205]: eth1: Link UP142gamma # [6500627.204375] gamma systemd-networkd[205]: eth1: Gained carrier143gamma # [6500627.204806] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...144gamma # [6500627.242091] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.145gamma # [6500627.427296] gamma systemd-resolved[114]: Positive Trust Anchors:146gamma # [6500627.427309] gamma systemd-resolved[114]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d147gamma # [6500627.427312] gamma systemd-resolved[114]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16148gamma # [6500627.427347] gamma systemd-resolved[114]: 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 test149gamma # [6500627.450618] gamma systemd-resolved[114]: Using system hostname 'gamma'.150alpha # [6500627.194873] alpha systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted151alpha # [6500627.194962] alpha systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted152alpha # [6500627.202503] alpha 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.153alpha # [6500627.202672] alpha 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.154alpha # [6500627.202838] alpha systemd-networkd[205]: lo: Link UP155alpha # [6500627.202841] alpha systemd-networkd[205]: lo: Gained carrier156alpha # [6500627.203009] alpha systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.157alpha # [6500627.203555] alpha systemd-networkd[205]: eth1: Link UP158alpha # [6500627.203858] alpha systemd[1]: Started Network Management.159alpha # [6500627.204854] alpha systemd-networkd[205]: eth1: Gained carrier160alpha # [6500627.205163] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...161alpha # [6500627.241560] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.162alpha # [6500627.434189] alpha systemd-resolved[112]: Positive Trust Anchors:163alpha # [6500627.434201] alpha systemd-resolved[112]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d164alpha # [6500627.434205] alpha systemd-resolved[112]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16165alpha # [6500627.434242] alpha systemd-resolved[112]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test166alpha # [6500627.458554] alpha systemd-resolved[112]: Using system hostname 'alpha'.167beta # [6500627.210419] beta systemd-networkd[205]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted168beta # [6500627.210518] beta systemd-networkd[205]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted169beta # [6500627.225327] 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.170beta # [6500627.225492] 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.171beta # [6500627.225660] beta systemd-networkd[205]: lo: Link UP172beta # [6500627.225665] beta systemd-networkd[205]: lo: Gained carrier173beta # [6500627.225849] beta systemd-networkd[205]: eth1: Configuring with /etc/systemd/network/40-eth1.network.174beta # [6500627.226260] beta systemd[1]: Started Network Management.175beta # [6500627.226351] beta systemd-networkd[205]: eth1: Link UP176beta # [6500627.226573] beta systemd-networkd[205]: eth1: Gained carrier177beta # [6500627.227194] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...178beta # [6500627.262034] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.179beta # [6500627.425463] beta systemd-resolved[114]: Positive Trust Anchors:180beta # [6500627.425474] beta systemd-resolved[114]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d181beta # [6500627.425478] beta systemd-resolved[114]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16182beta # [6500627.425514] beta systemd-resolved[114]: 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 test183beta # [6500627.448147] beta systemd-resolved[114]: Using system hostname 'beta'.184beta # [6500627.449531] beta systemd[1]: Started Network Name Resolution.185beta # [6500627.449616] beta systemd[1]: Reached target Network.186beta # [6500627.449683] beta systemd[1]: Reached target System Initialization.187beta # [6500627.449734] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container188beta # [6500627.449761] beta systemd[1]: Started Daily Cleanup of Temporary Directories.189beta # [6500627.449778] beta systemd[1]: Reached target Timer Units.190beta # [6500627.449899] beta systemd[1]: Listening on D-Bus System Message Bus Socket.191beta # [6500627.450023] beta systemd[1]: Listening on Nix Daemon Socket.192beta # [6500627.450137] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.193beta # [6500627.450159] beta systemd[1]: Reached target Socket Units.194beta # [6500627.450197] beta systemd[1]: Reached target Basic System.195beta # [6500627.451901] beta systemd[1]: Starting data mesher daemon...196beta # [6500627.452722] beta systemd[1]: Starting Import lastlog data into lastlog2 database...197beta # [6500627.453592] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...198beta # [6500627.455265] beta systemd[1]: Starting D-Bus System Message Bus...199beta # [6500627.472573] beta systemd[1]: Finished Import lastlog data into lastlog2 database.200beta # [6500627.514988] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.201beta # [6500627.574682] beta nsncd[212]: Aug 23 05:07:33.627 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"202beta # [6500627.574754] beta systemd[1]: Started Name Service Cache Daemon (nsncd).203beta # [6500627.574821] beta systemd[1]: Reached target Host and Network Name Lookups.204beta # [6500627.574895] beta systemd[1]: Reached target User and Group Name Lookups.205beta # [6500627.576680] beta systemd[1]: Starting User Login Management...206beta # [6500627.577596] beta systemd[1]: Starting Permit User Sessions...207beta # [6500627.621363] beta systemd[1]: Finished Permit User Sessions.208beta # [6500627.622567] beta systemd[1]: Started Console Getty.209beta # [6500627.622618] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0210beta # [6500627.622643] beta systemd[1]: Reached target Login Prompts.211beta # [6500627.673140] beta dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...212beta # [6500627.673839] beta dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'213beta # [6500627.673839] beta dbus-broker-launch[213]: Invalid user-name in /nix/store/r467fybq8imqxxx1sakqq32ay68d83ra-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"214beta # [6500627.674247] beta systemd[1]: Started D-Bus System Message Bus.215beta # [6500627.681581] beta dbus-broker-launch[213]: Ready216gamma # [6500627.452140] gamma systemd[1]: Started Network Name Resolution.217gamma # [6500627.452232] gamma systemd[1]: Reached target Network.218gamma # [6500627.452305] gamma systemd[1]: Reached target System Initialization.219gamma # [6500627.452416] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container220gamma # [6500627.452445] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.221gamma # [6500627.452462] gamma systemd[1]: Reached target Timer Units.222gamma # [6500627.452948] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.223gamma # [6500627.453189] gamma systemd[1]: Listening on Nix Daemon Socket.224gamma # [6500627.453323] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.225gamma # [6500627.453347] gamma systemd[1]: Reached target Socket Units.226gamma # [6500627.453396] gamma systemd[1]: Reached target Basic System.227gamma # [6500627.455278] gamma systemd[1]: Starting data mesher daemon...228gamma # [6500627.456329] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...229gamma # [6500627.457204] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...230gamma # [6500627.458711] gamma systemd[1]: Starting D-Bus System Message Bus...231gamma # [6500627.473949] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.232gamma # [6500627.503172] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.233gamma # [6500627.571479] gamma nsncd[212]: Aug 23 05:07:33.624 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"234gamma # [6500627.571554] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).235gamma # [6500627.571644] gamma systemd[1]: Reached target Host and Network Name Lookups.236gamma # [6500627.571742] gamma systemd[1]: Reached target User and Group Name Lookups.237gamma # [6500627.573923] gamma systemd[1]: Starting User Login Management...238gamma # [6500627.575551] gamma systemd[1]: Starting Permit User Sessions...239gamma # [6500627.621501] gamma systemd[1]: Finished Permit User Sessions.240gamma # [6500627.622589] gamma systemd[1]: Started Console Getty.241gamma # [6500627.622636] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0242gamma # [6500627.622653] gamma systemd[1]: Reached target Login Prompts.243gamma # [6500627.683561] gamma dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...244gamma # [6500627.684613] gamma dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'245gamma # [6500627.684679] gamma dbus-broker-launch[213]: Invalid user-name in /nix/store/r467fybq8imqxxx1sakqq32ay68d83ra-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"246gamma # [6500627.685095] gamma systemd[1]: Started D-Bus System Message Bus.247gamma # [6500627.697358] gamma dbus-broker-launch[213]: Ready248alpha # [6500627.460027] alpha systemd[1]: Started Network Name Resolution.249alpha # [6500627.460123] alpha systemd[1]: Reached target Network.250alpha # [6500627.460187] alpha systemd[1]: Reached target System Initialization.251alpha # [6500627.460249] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container252alpha # [6500627.460274] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.253alpha # [6500627.460289] alpha systemd[1]: Reached target Timer Units.254alpha # [6500627.460421] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.255alpha # [6500627.460538] alpha systemd[1]: Listening on Nix Daemon Socket.256alpha # [6500627.460648] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.257alpha # [6500627.460667] alpha systemd[1]: Reached target Socket Units.258alpha # [6500627.460710] alpha systemd[1]: Reached target Basic System.259alpha # [6500627.462496] alpha systemd[1]: Starting data mesher daemon...260alpha # [6500627.463687] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...261alpha # [6500627.464528] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...262alpha # [6500627.465856] alpha systemd[1]: Starting D-Bus System Message Bus...263alpha # [6500627.484988] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.264alpha # [6500627.493999] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.265alpha # [6500627.573432] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).266alpha # [6500627.573706] alpha nsncd[212]: Aug 23 05:07:33.626 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"267alpha # [6500627.573484] alpha systemd[1]: Reached target Host and Network Name Lookups.268alpha # [6500627.573536] alpha systemd[1]: Reached target User and Group Name Lookups.269alpha # [6500627.574706] alpha systemd[1]: Starting User Login Management...270alpha # [6500627.575355] alpha systemd[1]: Starting Permit User Sessions...271alpha # [6500627.620622] alpha systemd[1]: Finished Permit User Sessions.272alpha # [6500627.621879] alpha systemd[1]: Started Console Getty.273alpha # [6500627.621928] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0274alpha # [6500627.621951] alpha systemd[1]: Reached target Login Prompts.275alpha # [6500627.672564] alpha dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...276alpha # [6500627.673380] alpha dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'277alpha # [6500627.673415] alpha dbus-broker-launch[213]: Invalid user-name in /nix/store/r467fybq8imqxxx1sakqq32ay68d83ra-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"278alpha # [6500627.673811] alpha systemd[1]: Started D-Bus System Message Bus.279alpha # [6500627.680723] alpha dbus-broker-launch[213]: Ready280alpha # [6500627.919109] alpha data-mesher[210]: time=2026-08-23T05:07:33.972Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]281alpha # [6500627.923049] alpha data-mesher[210]: time=2026-08-23T05:07:33.975Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6: [/dns/alpha.clan/tcp/7946]} {12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6282alpha # [6500627.923049] alpha data-mesher[210]: time=2026-08-23T05:07:33.975Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml283alpha # [6500627.930628] alpha data-mesher[210]: time=2026-08-23T05:07:33.983Z level=INFO msg="checking file integrity"284alpha # [6500627.930766] alpha data-mesher[210]: time=2026-08-23T05:07:33.983Z level=INFO msg="file integrity check complete"285alpha # [6500627.934878] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="libp2p host created" peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 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]"286alpha # [6500627.934942] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="registered HTTP route" method=GET path=/files287alpha # [6500627.934942] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name288alpha # [6500627.934942] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name289alpha # [6500627.934942] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="starting server"290alpha # [6500627.935087] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="waiting for DHT to populate" delay=10s291alpha # [6500627.935127] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="HTTP server listening" address=[::1]:7331292alpha # [6500627.935160] alpha data-mesher[210]: time=2026-08-23T05:07:33.988Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331293alpha # [6500627.945170] alpha data-mesher[210]: time=2026-08-23T05:07:33.998Z level=INFO msg="peer connected" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq remote_addr=/ip4/192.168.1.3/tcp/7946294alpha # [6500627.946819] alpha data-mesher[210]: time=2026-08-23T05:07:33.999Z level=INFO msg="peer connected" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 remote_addr=/ip4/192.168.1.2/tcp/7946295gamma # [6500627.931267] gamma data-mesher[210]: time=2026-08-23T05:07:33.984Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]296gamma # [6500627.932381] gamma data-mesher[210]: time=2026-08-23T05:07:33.985Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6: [/dns/alpha.clan/tcp/7946]} {12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq297gamma # [6500627.932438] gamma data-mesher[210]: time=2026-08-23T05:07:33.985Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml298gamma # [6500627.933567] gamma data-mesher[210]: time=2026-08-23T05:07:33.986Z level=INFO msg="checking file integrity"299gamma # [6500627.933673] gamma data-mesher[210]: time=2026-08-23T05:07:33.986Z level=INFO msg="file integrity check complete"300gamma # [6500627.937588] gamma data-mesher[210]: time=2026-08-23T05:07:33.990Z level=INFO msg="libp2p host created" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq 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]"301gamma # [6500627.937640] gamma data-mesher[210]: time=2026-08-23T05:07:33.990Z level=INFO msg="registered HTTP route" method=GET path=/files302gamma # [6500627.937640] gamma data-mesher[210]: time=2026-08-23T05:07:33.990Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name303gamma # [6500627.937640] gamma data-mesher[210]: time=2026-08-23T05:07:33.990Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name304gamma # [6500627.937640] gamma data-mesher[210]: time=2026-08-23T05:07:33.990Z level=INFO msg="starting server"305gamma # [6500627.937749] gamma data-mesher[210]: time=2026-08-23T05:07:33.990Z level=INFO msg="waiting for DHT to populate" delay=10s306gamma # [6500627.937817] gamma data-mesher[210]: time=2026-08-23T05:07:33.990Z level=INFO msg="HTTP server listening" address=[::1]:7331307gamma # [6500627.937850] gamma data-mesher[210]: time=2026-08-23T05:07:33.991Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331308gamma # [6500627.943718] gamma data-mesher[210]: time=2026-08-23T05:07:33.996Z level=INFO msg="peer connected" peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 remote_addr=/ip4/192.168.1.1/tcp/7946309gamma # [6500627.950231] gamma data-mesher[210]: time=2026-08-23T05:07:34.003Z level=INFO msg="peer connected" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 remote_addr=/ip4/192.168.1.2/tcp/7946310gamma # [6500627.953961] gamma data-mesher[210]: time=2026-08-23T05:07:34.007Z level=INFO msg="peer connected" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 remote_addr=/ip4/192.168.1.2/tcp/34098311beta # [6500627.924381] beta data-mesher[210]: time=2026-08-23T05:07:33.977Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]312beta # [6500627.925471] beta data-mesher[210]: time=2026-08-23T05:07:33.978Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6: [/dns/alpha.clan/tcp/7946]} {12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3313beta # [6500627.925521] beta data-mesher[210]: time=2026-08-23T05:07:33.978Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml314beta # [6500627.930784] beta data-mesher[210]: time=2026-08-23T05:07:33.983Z level=INFO msg="checking file integrity"315beta # [6500627.931017] beta data-mesher[210]: time=2026-08-23T05:07:33.984Z level=INFO msg="file integrity check complete"316beta # [6500627.940104] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="libp2p host created" peer_id=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 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]"317beta # [6500627.940179] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="registered HTTP route" method=GET path=/files318beta # [6500627.940179] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name319beta # [6500627.940219] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name320beta # [6500627.940219] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="starting server"321beta # [6500627.940396] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="HTTP server listening" address=[::1]:7331322beta # [6500627.940417] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="waiting for DHT to populate" delay=10s323beta # [6500627.940417] beta data-mesher[210]: time=2026-08-23T05:07:33.993Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331324beta # [6500627.946203] beta data-mesher[210]: time=2026-08-23T05:07:33.999Z level=INFO msg="peer connected" peer_id=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 remote_addr=/ip4/192.168.1.1/tcp/7946325beta # [6500627.950863] beta data-mesher[210]: time=2026-08-23T05:07:34.004Z level=INFO msg="peer connected" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq remote_addr=/ip4/192.168.1.3/tcp/7946326beta # [6500627.953222] beta data-mesher[210]: time=2026-08-23T05:07:34.006Z level=INFO msg="peer connected" peer_id=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq remote_addr=/ip4/192.168.1.3/tcp/7946327gamma # [6500628.217878] gamma systemd-logind[229]: New seat seat0.328beta # [6500628.231440] beta systemd-logind[229]: New seat seat0.329gamma # [6500628.218067] gamma systemd[1]: Started User Login Management.330beta # [6500628.231641] beta systemd[1]: Started User Login Management.331gamma # [6500628.219338] gamma systemd[1]: Starting linger-users.service...332beta # [6500628.232958] beta systemd[1]: Starting linger-users.service...333gamma # [6500628.230931] gamma systemd[1]: linger-users.service: Deactivated successfully.334beta # [6500628.243981] beta systemd[1]: linger-users.service: Deactivated successfully.335gamma # [6500628.230995] gamma systemd[1]: Finished linger-users.service.336beta # [6500628.244219] beta systemd[1]: Finished linger-users.service.337alpha # [6500628.209005] alpha systemd-logind[229]: New seat seat0.338alpha # [6500628.209213] alpha systemd[1]: Started User Login Management.339alpha # [6500628.211361] alpha systemd[1]: Starting linger-users.service...340alpha # [6500628.222183] alpha systemd[1]: linger-users.service: Deactivated successfully.341alpha # [6500628.222327] alpha systemd[1]: Finished linger-users.service.342beta # [6500628.704253] beta systemd-networkd[205]: eth1: Gained IPv6LL343gamma # [6500628.772467] gamma systemd-networkd[205]: eth1: Gained IPv6LL344alpha # [6500629.088188] alpha systemd-networkd[205]: eth1: Gained IPv6LL345alpha: still waiting for container 'alpha' to reach ready state...346beta # [6500637.936271] beta data-mesher[210]: time=2026-08-23T05:07:43.989Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6347beta # [6500637.936271] beta data-mesher[210]: time=2026-08-23T05:07:43.989Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6348beta # [6500637.940583] beta data-mesher[210]: time=2026-08-23T05:07:43.993Z level=INFO msg="performing state exchange with peers on join" count=1349beta # [6500637.940704] beta data-mesher[210]: time=2026-08-23T05:07:43.993Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s350beta # [6500637.941472] beta data-mesher[210]: time=2026-08-23T05:07:43.994Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq351beta # [6500637.941472] beta data-mesher[210]: time=2026-08-23T05:07:43.994Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s352beta # [6500637.941610] beta data-mesher[210]: time=2026-08-23T05:07:43.994Z level=INFO msg="server started"353beta # [6500637.941709] beta data-mesher[210]: time=2026-08-23T05:07:43.994Z level=INFO msg="starting expired-file sweeper" interval=1m0s354beta # [6500637.941836] beta systemd[1]: Started data mesher daemon.355beta # [6500637.942374] beta systemd[1]: Reached target Multi-User System.356beta # [6500637.942853] beta systemd[1]: Startup finished in 11.817s.357gamma # [6500637.938373] gamma data-mesher[210]: time=2026-08-23T05:07:43.991Z level=INFO msg="performing state exchange with peers on join" count=1358gamma # [6500637.938819] gamma data-mesher[210]: time=2026-08-23T05:07:43.991Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s359gamma # [6500637.939340] gamma data-mesher[210]: time=2026-08-23T05:07:43.992Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6360gamma # [6500637.939340] gamma data-mesher[210]: time=2026-08-23T05:07:43.992Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s361gamma # [6500637.939426] gamma data-mesher[210]: time=2026-08-23T05:07:43.992Z level=INFO msg="server started"362gamma # [6500637.939567] gamma data-mesher[210]: time=2026-08-23T05:07:43.992Z level=INFO msg="starting expired-file sweeper" interval=1m0s363gamma # [6500637.939682] gamma systemd[1]: Started data mesher daemon.364gamma # [6500637.940239] gamma systemd[1]: Reached target Multi-User System.365gamma # [6500637.940564] gamma systemd[1]: Startup finished in 11.820s.366gamma # [6500637.941282] gamma data-mesher[210]: time=2026-08-23T05:07:43.994Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3367gamma # [6500637.941282] gamma data-mesher[210]: time=2026-08-23T05:07:43.994Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3368alpha: (finished: waiting for unit data-mesher.service, in 12.68 seconds)369beta: waiting for unit data-mesher.service370beta: (finished: waiting for unit data-mesher.service, in 0.02 seconds)371gamma: waiting for unit data-mesher.service372gamma: (finished: waiting for unit data-mesher.service, in 0.02 seconds)373alpha: must succeed: echo -n 'hello world' > /tmp/test_file374alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.01 seconds)375alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key376alpha # [6500637.935453] alpha data-mesher[210]: time=2026-08-23T05:07:43.988Z level=INFO msg="performing state exchange with peers on join" count=1377alpha # [6500637.935453] alpha data-mesher[210]: time=2026-08-23T05:07:43.988Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s378alpha # [6500637.936439] alpha data-mesher[210]: time=2026-08-23T05:07:43.989Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3379alpha # [6500637.936439] alpha data-mesher[210]: time=2026-08-23T05:07:43.989Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s380alpha # [6500637.936587] alpha data-mesher[210]: time=2026-08-23T05:07:43.989Z level=INFO msg="server started"381alpha # [6500637.936587] alpha data-mesher[210]: time=2026-08-23T05:07:43.989Z level=INFO msg="starting expired-file sweeper" interval=1m0s382alpha # [6500637.936737] alpha systemd[1]: Started data mesher daemon.383alpha # [6500637.937286] alpha systemd[1]: Reached target Multi-User System.384alpha # [6500637.937608] alpha systemd[1]: Startup finished in 11.812s.385alpha # [6500637.939053] alpha data-mesher[210]: time=2026-08-23T05:07:43.992Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq386alpha # [6500637.939053] alpha data-mesher[210]: time=2026-08-23T05:07:43.992Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq387alpha # [6500638.415780] alpha data-mesher[210]: time=2026-08-23T05:07:44.468Z level=INFO msg=http_request uri=/files/test_file status=204388alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)389??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.390 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39391alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file392??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.393 File "/nix/store/258dmf26bj4wx2idmzbn2vz4hzlmk8bh-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39394alpha: (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)395beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file396beta # [6500642.942296] beta data-mesher[210]: time=2026-08-23T05:07:48.995Z level=DEBUG msg="attempting push/pull" peer_count=2397alpha # [6500642.937431] alpha data-mesher[210]: time=2026-08-23T05:07:48.990Z level=DEBUG msg="attempting push/pull" peer_count=2398beta # [6500642.942769] beta data-mesher[210]: time=2026-08-23T05:07:48.995Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s399alpha # [6500642.938135] alpha data-mesher[210]: time=2026-08-23T05:07:48.990Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s400alpha # [6500642.938433] alpha data-mesher[210]: time=2026-08-23T05:07:48.991Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq401gamma # [6500642.938188] gamma data-mesher[210]: time=2026-08-23T05:07:48.991Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6402alpha # [6500642.938433] alpha data-mesher[210]: time=2026-08-23T05:07:48.991Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s403gamma # [6500642.938188] gamma data-mesher[210]: time=2026-08-23T05:07:48.991Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6404beta # [6500642.942966] beta data-mesher[210]: time=2026-08-23T05:07:48.996Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq405gamma # [6500642.938595] gamma data-mesher[210]: time=2026-08-23T05:07:48.991Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_file406beta # [6500642.942996] beta data-mesher[210]: time=2026-08-23T05:07:48.996Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s407gamma # [6500642.938595] gamma data-mesher[210]: time=2026-08-23T05:07:48.991Z level=INFO msg="scheduling file download" name=test_file408beta # [6500642.943037] beta data-mesher[210]: time=2026-08-23T05:07:48.996Z level=DEBUG msg="push/pull successful" interval=5s409gamma # [6500642.938595] gamma data-mesher[210]: time=2026-08-23T05:07:48.991Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-23 05:07:44.463 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6410alpha # [6500642.938547] alpha data-mesher[210]: time=2026-08-23T05:07:48.991Z level=DEBUG msg="push/pull successful" interval=5s411gamma # [6500642.939758] gamma data-mesher[210]: time=2026-08-23T05:07:48.992Z level=DEBUG msg="attempting push/pull" peer_count=2412alpha # [6500642.938935] alpha data-mesher[210]: time=2026-08-23T05:07:48.992Z level=INFO msg="received file request" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file413gamma # [6500642.940198] gamma data-mesher[210]: time=2026-08-23T05:07:48.993Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s414alpha # [6500642.940927] alpha data-mesher[210]: time=2026-08-23T05:07:48.994Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq415gamma # [6500642.941506] gamma data-mesher[210]: time=2026-08-23T05:07:48.994Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6416alpha # [6500642.941002] alpha data-mesher[210]: time=2026-08-23T05:07:48.994Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq417gamma # [6500642.941539] gamma data-mesher[210]: time=2026-08-23T05:07:48.994Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_file418alpha # [6500642.941375] alpha data-mesher[210]: time=2026-08-23T05:07:48.994Z level=INFO msg="file transfer complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file419gamma # [6500642.941539] gamma data-mesher[210]: time=2026-08-23T05:07:48.994Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s420gamma # [6500642.941580] gamma data-mesher[210]: time=2026-08-23T05:07:48.994Z level=DEBUG msg="push/pull successful" interval=5s421gamma # [6500642.942627] gamma data-mesher[210]: time=2026-08-23T05:07:48.995Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3422gamma # [6500642.942664] gamma data-mesher[210]: time=2026-08-23T05:07:48.995Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3423gamma # [6500642.953173] gamma data-mesher[210]: time=2026-08-23T05:07:49.006Z level=INFO msg="download complete" name=test_file signed_at="2026-08-23 05:07:44.463 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=14.706562ms424beta # [6500647.939963] beta data-mesher[210]: time=2026-08-23T05:07:53.993Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6425beta # [6500647.939963] beta data-mesher[210]: time=2026-08-23T05:07:53.993Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6426beta # [6500647.940700] beta data-mesher[210]: time=2026-08-23T05:07:53.993Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_file427beta # [6500647.940700] beta data-mesher[210]: time=2026-08-23T05:07:53.993Z level=INFO msg="scheduling file download" name=test_file428beta # [6500647.940700] beta data-mesher[210]: time=2026-08-23T05:07:53.993Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-23 05:07:44.463 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6429beta # [6500647.943303] beta data-mesher[210]: time=2026-08-23T05:07:53.996Z level=DEBUG msg="attempting push/pull" peer_count=2430beta # [6500647.943383] beta data-mesher[210]: time=2026-08-23T05:07:53.996Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s431beta # [6500647.944174] beta data-mesher[210]: time=2026-08-23T05:07:53.997Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6432beta # [6500647.944240] beta data-mesher[210]: time=2026-08-23T05:07:53.997Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_file433beta # [6500647.944240] beta data-mesher[210]: time=2026-08-23T05:07:53.997Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s434beta # [6500647.944339] beta data-mesher[210]: time=2026-08-23T05:07:53.997Z level=DEBUG msg="push/pull successful" interval=5s435beta # [6500647.947419] beta data-mesher[210]: time=2026-08-23T05:07:54.000Z level=INFO msg="download complete" name=test_file signed_at="2026-08-23 05:07:44.463 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=7.219979ms436gamma # [6500647.942632] gamma data-mesher[210]: time=2026-08-23T05:07:53.995Z level=DEBUG msg="attempting push/pull" peer_count=2437gamma # [6500647.942955] gamma data-mesher[210]: time=2026-08-23T05:07:53.995Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s438gamma # [6500647.943731] gamma data-mesher[210]: time=2026-08-23T05:07:53.996Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6439gamma # [6500647.943792] gamma data-mesher[210]: time=2026-08-23T05:07:53.996Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s440gamma # [6500647.943860] gamma data-mesher[210]: time=2026-08-23T05:07:53.997Z level=DEBUG msg="push/pull successful" interval=5s441alpha # [6500647.939120] alpha data-mesher[210]: time=2026-08-23T05:07:53.992Z level=DEBUG msg="attempting push/pull" peer_count=2442alpha # [6500647.939814] alpha data-mesher[210]: time=2026-08-23T05:07:53.992Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s443alpha # [6500647.940353] alpha data-mesher[210]: time=2026-08-23T05:07:53.993Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3444alpha # [6500647.940353] alpha data-mesher[210]: time=2026-08-23T05:07:53.993Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s445alpha # [6500647.940483] alpha data-mesher[210]: time=2026-08-23T05:07:53.993Z level=DEBUG msg="push/pull successful" interval=5s446alpha # [6500647.940819] alpha data-mesher[210]: time=2026-08-23T05:07:53.993Z level=INFO msg="received file request" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file447alpha # [6500647.942484] alpha data-mesher[210]: time=2026-08-23T05:07:53.995Z level=INFO msg="file transfer complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_file448alpha # [6500647.943346] alpha data-mesher[210]: time=2026-08-23T05:07:53.996Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq449alpha # [6500647.943404] alpha data-mesher[210]: time=2026-08-23T05:07:53.996Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq450alpha # [6500647.943808] alpha data-mesher[210]: time=2026-08-23T05:07:53.996Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3451alpha # [6500647.943872] alpha data-mesher[210]: time=2026-08-23T05:07:53.996Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3452beta: (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.09 seconds)453gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_file && grep -q 'hello world' /var/lib/data-mesher/files/home/test_file454gamma: (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)455beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key456beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.03 seconds)457alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file458beta # [6500648.552370] beta data-mesher[210]: time=2026-08-23T05:07:54.605Z level=INFO msg=http_request uri=/files/test_file status=204459beta # [6500652.941325] beta data-mesher[210]: time=2026-08-23T05:07:58.994Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6460beta # [6500652.941325] beta data-mesher[210]: time=2026-08-23T05:07:58.994Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6461beta # [6500652.944421] beta data-mesher[210]: time=2026-08-23T05:07:58.997Z level=DEBUG msg="attempting push/pull" peer_count=2462beta # [6500652.944468] beta data-mesher[210]: time=2026-08-23T05:07:58.997Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s463beta # [6500652.945053] beta data-mesher[210]: time=2026-08-23T05:07:58.998Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq464beta # [6500652.945080] beta data-mesher[210]: time=2026-08-23T05:07:58.998Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq465beta # [6500652.964201] beta data-mesher[210]: time=2026-08-23T05:07:59.017Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6466beta # [6500652.964244] beta data-mesher[210]: time=2026-08-23T05:07:59.017Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s467beta # [6500652.964304] beta data-mesher[210]: time=2026-08-23T05:07:59.017Z level=DEBUG msg="push/pull successful" interval=5s468gamma # [6500652.944468] gamma data-mesher[210]: time=2026-08-23T05:07:58.997Z level=DEBUG msg="attempting push/pull" peer_count=2469gamma # [6500652.944468] gamma data-mesher[210]: time=2026-08-23T05:07:58.997Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s470gamma # [6500652.945386] gamma data-mesher[210]: time=2026-08-23T05:07:58.998Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3471gamma # [6500652.963925] gamma data-mesher[210]: time=2026-08-23T05:07:59.017Z level=DEBUG msg="imported tombstone" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 name=test_file written=true472gamma # [6500652.963925] gamma data-mesher[210]: time=2026-08-23T05:07:59.017Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s473gamma # [6500652.964101] gamma data-mesher[210]: time=2026-08-23T05:07:59.017Z level=DEBUG msg="push/pull successful" interval=5s474alpha # [6500652.940563] alpha data-mesher[210]: time=2026-08-23T05:07:58.993Z level=DEBUG msg="attempting push/pull" peer_count=2475alpha # [6500652.941254] alpha data-mesher[210]: time=2026-08-23T05:07:58.993Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s476alpha # [6500652.941608] alpha data-mesher[210]: time=2026-08-23T05:07:58.994Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3477alpha # [6500652.944850] alpha data-mesher[210]: time=2026-08-23T05:07:58.998Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3478alpha # [6500652.944850] alpha data-mesher[210]: time=2026-08-23T05:07:58.998Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3479alpha # [6500652.963659] alpha data-mesher[210]: time=2026-08-23T05:07:59.016Z level=DEBUG msg="imported tombstone" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 name=test_file written=true480alpha # [6500652.963659] alpha data-mesher[210]: time=2026-08-23T05:07:59.016Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s481alpha # [6500652.963787] alpha data-mesher[210]: time=2026-08-23T05:07:59.016Z level=DEBUG msg="push/pull successful" interval=5s482alpha # [6500652.963787] alpha data-mesher[210]: time=2026-08-23T05:07:59.016Z level=DEBUG msg="imported tombstone" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 name=test_file written=false483alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 5.04 seconds)484beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file485beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)486gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file487gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.01 seconds)488alpha: must succeed: cat /nix/store/hw6512jcgmq95kz2zrg7yi21w3zia2rx-per-machine-alpha-data-mesher-node-identity_identity.pub489alpha: (finished: must succeed: cat /nix/store/hw6512jcgmq95kz2zrg7yi21w3zia2rx-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.01 seconds)490alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file491alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.01 seconds)492alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert493alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.03 seconds)494alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU495alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU, in 0.01 seconds)496beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU497alpha # [6500653.655171] alpha data-mesher[210]: time=2026-08-23T05:07:59.708Z level=INFO msg=http_request uri=/files/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU status=204498beta # [6500657.965391] beta data-mesher[210]: time=2026-08-23T05:08:04.018Z level=DEBUG msg="attempting push/pull" peer_count=2499beta # [6500657.965391] beta data-mesher[210]: time=2026-08-23T05:08:04.018Z level=DEBUG msg="initiating state exchange" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s500beta # [6500657.965391] beta data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="received state sync from peer" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq501beta # [6500657.965391] beta data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq502beta # [6500657.966275] beta data-mesher[210]: time=2026-08-23T05:08:04.019Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6503beta # [6500657.966747] beta data-mesher[210]: time=2026-08-23T05:08:04.019Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU504beta # [6500657.966747] beta data-mesher[210]: time=2026-08-23T05:08:04.019Z level=INFO msg="state exchange complete" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 timeout=5s505beta # [6500657.966747] beta data-mesher[210]: time=2026-08-23T05:08:04.019Z level=INFO msg="scheduling file download" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU506beta # [6500657.966747] beta data-mesher[210]: time=2026-08-23T05:08:04.019Z level=DEBUG msg="push/pull successful" interval=5s507beta # [6500657.966747] beta data-mesher[210]: time=2026-08-23T05:08:04.019Z level=INFO msg="downloading file" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-23 05:07:59.706 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6508beta # [6500657.971484] beta data-mesher[210]: time=2026-08-23T05:08:04.024Z level=INFO msg="download complete" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-23 05:07:59.706 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=4.745106ms509gamma # [6500657.964757] gamma data-mesher[210]: time=2026-08-23T05:08:04.017Z level=DEBUG msg="attempting push/pull" peer_count=2510gamma # [6500657.965489] gamma data-mesher[210]: time=2026-08-23T05:08:04.017Z level=DEBUG msg="initiating state exchange" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s511gamma # [6500657.965489] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="received state sync from peer" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6512gamma # [6500657.965489] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="merging remote state" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6513gamma # [6500657.965647] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=DEBUG msg="new file detected" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 name=test_file name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU514gamma # [6500657.965702] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3515gamma # [6500657.965753] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="state exchange complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 timeout=5s516gamma # [6500657.965804] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="scheduling file download" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU517gamma # [6500657.965804] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=DEBUG msg="push/pull successful" interval=5s518gamma # [6500657.965895] gamma data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="downloading file" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-23 05:07:59.706 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6519gamma # [6500657.969147] gamma data-mesher[210]: time=2026-08-23T05:08:04.022Z level=INFO msg="download complete" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU signed_at="2026-08-23 05:07:59.706 +0000 UTC" signed_by="EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU=" peer=12D3KooWAtwZN69cWy5PMjZwgpqSwiNEJ1N1RU3oxFthKvH8yeF6 written=true elapsed=3.326086ms520alpha # [6500657.964255] alpha data-mesher[210]: time=2026-08-23T05:08:04.017Z level=DEBUG msg="attempting push/pull" peer_count=2521alpha # [6500657.964255] alpha data-mesher[210]: time=2026-08-23T05:08:04.017Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s522alpha # [6500657.965578] alpha data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="received state sync from peer" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3523alpha # [6500657.965578] alpha data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="merging remote state" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3524alpha # [6500657.965787] alpha data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="merging remote state" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq525alpha # [6500657.965839] alpha data-mesher[210]: time=2026-08-23T05:08:04.018Z level=INFO msg="state exchange complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq timeout=5s526alpha # [6500657.965839] alpha data-mesher[210]: time=2026-08-23T05:08:04.019Z level=DEBUG msg="push/pull successful" interval=5s527alpha # [6500657.966197] alpha data-mesher[210]: time=2026-08-23T05:08:04.019Z level=INFO msg="received file request" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU528alpha # [6500657.966824] alpha data-mesher[210]: time=2026-08-23T05:08:04.019Z level=INFO msg="file transfer complete" peer=12D3KooWRrumLUuiZwycnutKcspiGc3NWRktjwnEZWYnvwDSk7pq network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU529alpha # [6500657.967236] alpha data-mesher[210]: time=2026-08-23T05:08:04.020Z level=INFO msg="received file request" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU530alpha # [6500657.969948] alpha data-mesher[210]: time=2026-08-23T05:08:04.023Z level=INFO msg="file transfer complete" peer=12D3KooW9rnrisfUBm9WMbM8psXNrXVpcVpbJeAg2ecpktWsxxL3 network="3D8IZ5fqdOCRn13tTxYeG8VBr7r6G1tsF9xP49h42d0=" name=test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU531beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU, in 5.05 seconds)532gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU533gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU, in 0.01 seconds)534alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert535alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.01 seconds)536alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub537Error: failed to update file: 403 Forbidden, signer EAfDrMsAyzx/O0X2tXut83PVizS9uNVow8YCtvPrhdU= is not authorized for this file test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU538alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/i662g5nc00nm69g46rz14s8lpx978m49-shared-data-mesher-network_network.pub, in 0.03 seconds)539(finished: run the VM test script, in 33.10 seconds)540alpha # [6500658.759708] alpha data-mesher[210]: time=2026-08-23T05:08:04.812Z level=INFO msg=http_request uri=/files/test_ns/EAfDrMsAyzx_O0X2tXut83PVizS9uNVow8YCtvPrhdU status=403541test script finished in 36.91s542cleanup543kill NspawnMachine (pid 53)544kill NspawnMachine (pid 55)545kill NspawnMachine (pid 57)546Container alpha terminated by signal KILL.547Container beta terminated by signal KILL.548Container gamma terminated by signal KILL.549(finished: cleanup, in 0.39 seconds)

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

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