these 29 derivations will be built:
  /nix/store/r9j5x7yd5vg3jzxskrmkmw206dr1bx0n-system-path.drv
  /nix/store/al3wz748f6iq5p346n58s5nqb59nhlh5-dbus-1.drv
  /nix/store/0a48hfibsbaiik1qx3hrjv5dyldljg65-X-Restart-Triggers-dbus-broker.drv
  /nix/store/jsy9jpsmxlmk9xc0184r2mrnmdw5lc9j-python3.14-nixos-test-lib-1.0.0.drv
  /nix/store/3f2krm64b4w2q1axaplbxg5pi1h7bs4p-nixos-test-driver-1.1.drv
  /nix/store/k54bc4lkqdsmswd2r0fji15xbf2n3cr5-nix.conf.drv
  /nix/store/rxanslx21dypyn8nz2dc1csd1ridca0y-X-Restart-Triggers-nix-daemon.drv
  /nix/store/slxdagr01h9j20wgi5q25nx4affpnniy-unit-nix-daemon.service.drv
  /nix/store/ynvvi8w3b2fm6gnw88diff9p6fsp1gk6-unit-dbus-broker.service.drv
  /nix/store/ganvbcn7bjk3wjcymm28rr15d69j0gsg-system-units.drv
  /nix/store/3myxf2dllcyj3rz6zjksfdg50kchkyfb-unit-dbus-broker.service.drv
  /nix/store/pbmgldid3n4bb8jl0033cvj3sjknxjxk-user-units.drv
  /nix/store/pc3w5x262bf6s91c4lghjapzz97yi1lr-etc.drv
  /nix/store/nnpxws8ibmz8wbynmkzqjm1il33bx5r2-activate.drv
  /nix/store/kbpa1frs8n4ydbspq538lmamx57hb18a-nixos-system-gamma-test.drv
  /nix/store/45l70is4y1yy4m1xkv8ll386yli8dib1-run-gamma-nspawn.drv
  /nix/store/h9zr0ihplaagvllb03w1f424p8jsn5wv-system-units.drv
  /nix/store/gkqvjzpxl08bjphqygskxgdm8kma5b9p-etc.drv
  /nix/store/yawwxypbl85rprkqph1m0iy7qafg481f-activate.drv
  /nix/store/fgwmkdliw1zpga6xvlkwm4rb2c5lnin5-nixos-system-alpha-test.drv
  /nix/store/ccvwi2sdqdgyi5dwbwzl5xc9gs5z2if7-run-alpha-nspawn.drv
  /nix/store/agkg1933p9nwbkif22x681xm3a4pxqjx-system-units.drv
  /nix/store/al7y6fkwdxk6cw6wczw94r5cip2f13lp-etc.drv
  /nix/store/qfsn8rcjc5mklvq0xx0zmsh843y4ls5v-activate.drv
  /nix/store/901q4zr2lapnglzbmkwh2pki2f4c1ndx-nixos-system-beta-test.drv
  /nix/store/ymchnjyqbbkd2jmh9p66x6dryrcrcx0h-run-beta-nspawn.drv
  /nix/store/h7yjv0gn5xxmvw8amp05ynikbx3qfywi-driverConfiguration.json.drv
  /nix/store/21spqf76mxf1r44x9zby24f8j468fab2-nixos-test-driver-data-mesher.drv
  /nix/store/s437iqkg8ds95bhk0qfdrc1riwd9a1f4-container-test-run-data-mesher.drv
these 4 paths will be fetched (12.3 MiB download, 32.0 MiB unpacked):
  /nix/store/7gg2jzncxb747nxkxi8nmw7v8912xbli-python3.14-ruff-0.16.3
  /nix/store/jyz2q4g9b3m8rsinvf6w19b3brblm08w-python3.14-ty-0.0.73
  /nix/store/1dilm6b1irlgjfi10mpjif3bfjjjir6x-run-nspawn-1.0
  /nix/store/7nfy70lprap2qnn1586phaagrakxx4ag-ty-0.0.73
building '/nix/store/r9j5x7yd5vg3jzxskrmkmw206dr1bx0n-system-path.drv'
building '/nix/store/k54bc4lkqdsmswd2r0fji15xbf2n3cr5-nix.conf.drv'
system-path> structuredAttrs is enabled
building '/nix/store/jsy9jpsmxlmk9xc0184r2mrnmdw5lc9j-python3.14-nixos-test-lib-1.0.0.drv' on 'ssh-ng://builder@build-x86-01.clan.lol'
nix.conf> Validating generated nix.conf
system-path> created 1723 symlinks in user environment
building '/nix/store/rxanslx21dypyn8nz2dc1csd1ridca0y-X-Restart-Triggers-nix-daemon.drv'
building '/nix/store/al3wz748f6iq5p346n58s5nqb59nhlh5-dbus-1.drv'
building '/nix/store/slxdagr01h9j20wgi5q25nx4affpnniy-unit-nix-daemon.service.drv'
unit-nix-daemon.service> structuredAttrs is enabled
building '/nix/store/0a48hfibsbaiik1qx3hrjv5dyldljg65-X-Restart-Triggers-dbus-broker.drv'
building '/nix/store/3myxf2dllcyj3rz6zjksfdg50kchkyfb-unit-dbus-broker.service.drv'
building '/nix/store/ynvvi8w3b2fm6gnw88diff9p6fsp1gk6-unit-dbus-broker.service.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/pbmgldid3n4bb8jl0033cvj3sjknxjxk-user-units.drv'
unit-dbus-broker.service> structuredAttrs is enabled
building '/nix/store/agkg1933p9nwbkif22x681xm3a4pxqjx-system-units.drv'
building '/nix/store/ganvbcn7bjk3wjcymm28rr15d69j0gsg-system-units.drv'
building '/nix/store/h9zr0ihplaagvllb03w1f424p8jsn5wv-system-units.drv'
building '/nix/store/al7y6fkwdxk6cw6wczw94r5cip2f13lp-etc.drv'
building '/nix/store/gkqvjzpxl08bjphqygskxgdm8kma5b9p-etc.drv'
building '/nix/store/pc3w5x262bf6s91c4lghjapzz97yi1lr-etc.drv'
building '/nix/store/qfsn8rcjc5mklvq0xx0zmsh843y4ls5v-activate.drv'
building '/nix/store/yawwxypbl85rprkqph1m0iy7qafg481f-activate.drv'
building '/nix/store/nnpxws8ibmz8wbynmkzqjm1il33bx5r2-activate.drv'
building '/nix/store/fgwmkdliw1zpga6xvlkwm4rb2c5lnin5-nixos-system-alpha-test.drv'
building '/nix/store/901q4zr2lapnglzbmkwh2pki2f4c1ndx-nixos-system-beta-test.drv'
nixos-system-alpha-test> structuredAttrs is enabled
building '/nix/store/kbpa1frs8n4ydbspq538lmamx57hb18a-nixos-system-gamma-test.drv'
nixos-system-beta-test> structuredAttrs is enabled
building '/nix/store/ccvwi2sdqdgyi5dwbwzl5xc9gs5z2if7-run-alpha-nspawn.drv'
nixos-system-gamma-test> structuredAttrs is enabled
building '/nix/store/ymchnjyqbbkd2jmh9p66x6dryrcrcx0h-run-beta-nspawn.drv'
building '/nix/store/45l70is4y1yy4m1xkv8ll386yli8dib1-run-gamma-nspawn.drv'
building '/nix/store/h7yjv0gn5xxmvw8amp05ynikbx3qfywi-driverConfiguration.json.drv'
driverConfiguration.json> structuredAttrs is enabled
building '/nix/store/jsy9jpsmxlmk9xc0184r2mrnmdw5lc9j-python3.14-nixos-test-lib-1.0.0.drv'
python3.14-nixos-test-lib-1.0.0> Sourcing python-remove-tests-dir-hook
python3.14-nixos-test-lib-1.0.0> Sourcing python-catch-conflicts-hook.sh
python3.14-nixos-test-lib-1.0.0> Sourcing python-remove-bin-bytecode-hook.sh
python3.14-nixos-test-lib-1.0.0> Sourcing pypa-build-hook
python3.14-nixos-test-lib-1.0.0> Using pypaBuildPhase
python3.14-nixos-test-lib-1.0.0> Sourcing python-runtime-deps-check-hook
python3.14-nixos-test-lib-1.0.0> Using pythonRuntimeDepsCheckHook
python3.14-nixos-test-lib-1.0.0> Sourcing pypa-install-hook
python3.14-nixos-test-lib-1.0.0> Using pypaInstallPhase
python3.14-nixos-test-lib-1.0.0> Sourcing python-imports-check-hook.sh
python3.14-nixos-test-lib-1.0.0> Using pythonImportsCheckPhase
python3.14-nixos-test-lib-1.0.0> Sourcing python-namespaces-hook
python3.14-nixos-test-lib-1.0.0> Sourcing python-catch-conflicts-hook.sh
python3.14-nixos-test-lib-1.0.0> Running phase: unpackPhase
python3.14-nixos-test-lib-1.0.0> unpacking source archive /nix/store/7x1aaz1lsjpbv64fvax4ffnqjg8a37ks-source
python3.14-nixos-test-lib-1.0.0> source root is source
python3.14-nixos-test-lib-1.0.0> setting SOURCE_DATE_EPOCH to timestamp 315619200 of file "source/pyproject.toml"
python3.14-nixos-test-lib-1.0.0> Running phase: patchPhase
python3.14-nixos-test-lib-1.0.0> substituteStream() in derivation python3.14-nixos-test-lib-1.0.0: WARNING: '--replace' is deprecated, use --replace-{fail,warn,quiet}. (file 'nixos_test_lib/nix_setup.py')
python3.14-nixos-test-lib-1.0.0> Running phase: updateAutotoolsGnuConfigScriptsPhase
python3.14-nixos-test-lib-1.0.0> Running phase: configurePhase
python3.14-nixos-test-lib-1.0.0> no configure script, doing nothing
python3.14-nixos-test-lib-1.0.0> Running phase: buildPhase
python3.14-nixos-test-lib-1.0.0> Executing pypaBuildPhase
python3.14-nixos-test-lib-1.0.0> Creating a wheel...
python3.14-nixos-test-lib-1.0.0> pypa build flags: --no-isolation --outdir dist/ --wheel
python3.14-nixos-test-lib-1.0.0> * Getting build dependencies for wheel...
python3.14-nixos-test-lib-1.0.0> running egg_info
python3.14-nixos-test-lib-1.0.0> creating nixos_test_lib.egg-info
python3.14-nixos-test-lib-1.0.0> writing nixos_test_lib.egg-info/PKG-INFO
python3.14-nixos-test-lib-1.0.0> writing dependency_links to nixos_test_lib.egg-info/dependency_links.txt
python3.14-nixos-test-lib-1.0.0> writing requirements to nixos_test_lib.egg-info/requires.txt
python3.14-nixos-test-lib-1.0.0> writing top-level names to nixos_test_lib.egg-info/top_level.txt
python3.14-nixos-test-lib-1.0.0> writing manifest file 'nixos_test_lib.egg-info/SOURCES.txt'
python3.14-nixos-test-lib-1.0.0> reading manifest file 'nixos_test_lib.egg-info/SOURCES.txt'
python3.14-nixos-test-lib-1.0.0> writing manifest file 'nixos_test_lib.egg-info/SOURCES.txt'
python3.14-nixos-test-lib-1.0.0> * Building wheel...
python3.14-nixos-test-lib-1.0.0> running bdist_wheel
python3.14-nixos-test-lib-1.0.0> running build
python3.14-nixos-test-lib-1.0.0> running build_py
python3.14-nixos-test-lib-1.0.0> creating build/lib/nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/port.py -> build/lib/nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/ssh.py -> build/lib/nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/nix_setup.py -> build/lib/nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/__init__.py -> build/lib/nixos_test_lib
python3.14-nixos-test-lib-1.0.0> running egg_info
python3.14-nixos-test-lib-1.0.0> writing nixos_test_lib.egg-info/PKG-INFO
python3.14-nixos-test-lib-1.0.0> writing dependency_links to nixos_test_lib.egg-info/dependency_links.txt
python3.14-nixos-test-lib-1.0.0> writing requirements to nixos_test_lib.egg-info/requires.txt
python3.14-nixos-test-lib-1.0.0> writing top-level names to nixos_test_lib.egg-info/top_level.txt
python3.14-nixos-test-lib-1.0.0> reading manifest file 'nixos_test_lib.egg-info/SOURCES.txt'
python3.14-nixos-test-lib-1.0.0> writing manifest file 'nixos_test_lib.egg-info/SOURCES.txt'
python3.14-nixos-test-lib-1.0.0> copying nixos_test_lib/py.typed -> build/lib/nixos_test_lib
python3.14-nixos-test-lib-1.0.0> installing to build/bdist.linux-x86_64/wheel
python3.14-nixos-test-lib-1.0.0> running install
python3.14-nixos-test-lib-1.0.0> running install_lib
python3.14-nixos-test-lib-1.0.0> creating build/bdist.linux-x86_64/wheel
python3.14-nixos-test-lib-1.0.0> creating build/bdist.linux-x86_64/wheel/nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/port.py -> build/bdist.linux-x86_64/wheel/./nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/ssh.py -> build/bdist.linux-x86_64/wheel/./nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/nix_setup.py -> build/bdist.linux-x86_64/wheel/./nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/py.typed -> build/bdist.linux-x86_64/wheel/./nixos_test_lib
python3.14-nixos-test-lib-1.0.0> copying build/lib/nixos_test_lib/__init__.py -> build/bdist.linux-x86_64/wheel/./nixos_test_lib
python3.14-nixos-test-lib-1.0.0> running install_egg_info
python3.14-nixos-test-lib-1.0.0> Copying nixos_test_lib.egg-info to build/bdist.linux-x86_64/wheel/./nixos_test_lib-1.0.0-py3.14.egg-info
python3.14-nixos-test-lib-1.0.0> running install_scripts
python3.14-nixos-test-lib-1.0.0> creating build/bdist.linux-x86_64/wheel/nixos_test_lib-1.0.0.dist-info/WHEEL
python3.14-nixos-test-lib-1.0.0> creating '/build/source/dist/.tmp-hsehz2v2/nixos_test_lib-1.0.0-py3-none-any.whl' and adding 'build/bdist.linux-x86_64/wheel' to it
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/__init__.py'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/nix_setup.py'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/port.py'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/py.typed'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib/ssh.py'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/METADATA'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/WHEEL'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/top_level.txt'
python3.14-nixos-test-lib-1.0.0> adding 'nixos_test_lib-1.0.0.dist-info/RECORD'
python3.14-nixos-test-lib-1.0.0> removing build/bdist.linux-x86_64/wheel
python3.14-nixos-test-lib-1.0.0> Successfully built nixos_test_lib-1.0.0-py3-none-any.whl
python3.14-nixos-test-lib-1.0.0> Finished creating a wheel...
python3.14-nixos-test-lib-1.0.0> Finished executing pypaBuildPhase
python3.14-nixos-test-lib-1.0.0> Running phase: pythonRuntimeDepsCheckHook
python3.14-nixos-test-lib-1.0.0> Executing pythonRuntimeDepsCheck
python3.14-nixos-test-lib-1.0.0> Checking runtime dependencies for nixos_test_lib-1.0.0-py3-none-any.whl
python3.14-nixos-test-lib-1.0.0> Finished executing pythonRuntimeDepsCheck
python3.14-nixos-test-lib-1.0.0> Running phase: installPhase
python3.14-nixos-test-lib-1.0.0> Executing pypaInstallPhase
python3.14-nixos-test-lib-1.0.0> Successfully installed nixos_test_lib-1.0.0-py3-none-any.whl
python3.14-nixos-test-lib-1.0.0> Finished executing pypaInstallPhase
python3.14-nixos-test-lib-1.0.0> Running phase: pythonOutputDistPhase
python3.14-nixos-test-lib-1.0.0> Executing pythonOutputDistPhase
python3.14-nixos-test-lib-1.0.0> Finished executing pythonOutputDistPhase
python3.14-nixos-test-lib-1.0.0> Running phase: fixupPhase
python3.14-nixos-test-lib-1.0.0> shrinking RPATHs of ELF executables and libraries in /nix/store/ad0j8cdfrwvg2xf6dpgfhwnrhg579cic-python3.14-nixos-test-lib-1.0.0
python3.14-nixos-test-lib-1.0.0> checking for references to /build/ in /nix/store/ad0j8cdfrwvg2xf6dpgfhwnrhg579cic-python3.14-nixos-test-lib-1.0.0...
python3.14-nixos-test-lib-1.0.0> patching script interpreter paths in /nix/store/ad0j8cdfrwvg2xf6dpgfhwnrhg579cic-python3.14-nixos-test-lib-1.0.0
python3.14-nixos-test-lib-1.0.0> stripping (with command strip and flags -S -p) in /nix/store/ad0j8cdfrwvg2xf6dpgfhwnrhg579cic-python3.14-nixos-test-lib-1.0.0/lib
python3.14-nixos-test-lib-1.0.0> shrinking RPATHs of ELF executables and libraries in /nix/store/mvs8r59sw6xi5mxfdc5mmvz1fz2gdfsp-python3.14-nixos-test-lib-1.0.0-dist
python3.14-nixos-test-lib-1.0.0> checking for references to /build/ in /nix/store/mvs8r59sw6xi5mxfdc5mmvz1fz2gdfsp-python3.14-nixos-test-lib-1.0.0-dist...
python3.14-nixos-test-lib-1.0.0> patching script interpreter paths in /nix/store/mvs8r59sw6xi5mxfdc5mmvz1fz2gdfsp-python3.14-nixos-test-lib-1.0.0-dist
python3.14-nixos-test-lib-1.0.0> Executing pythonRemoveTestsDir
python3.14-nixos-test-lib-1.0.0> Finished executing pythonRemoveTestsDir
python3.14-nixos-test-lib-1.0.0> Running phase: pythonCatchConflictsPhase
python3.14-nixos-test-lib-1.0.0> Running phase: pythonRemoveBinBytecodePhase
python3.14-nixos-test-lib-1.0.0> Running phase: pythonImportsCheckPhase
python3.14-nixos-test-lib-1.0.0> Executing pythonImportsCheckPhase
building '/nix/store/3f2krm64b4w2q1axaplbxg5pi1h7bs4p-nixos-test-driver-1.1.drv' on 'ssh-ng://builder@build-x86-01.clan.lol'
building '/nix/store/3f2krm64b4w2q1axaplbxg5pi1h7bs4p-nixos-test-driver-1.1.drv'
nixos-test-driver-1.1> Sourcing python-remove-tests-dir-hook
nixos-test-driver-1.1> Sourcing python-catch-conflicts-hook.sh
nixos-test-driver-1.1> Sourcing python-remove-bin-bytecode-hook.sh
nixos-test-driver-1.1> Sourcing pypa-build-hook
nixos-test-driver-1.1> Using pypaBuildPhase
nixos-test-driver-1.1> Sourcing python-runtime-deps-check-hook
nixos-test-driver-1.1> Using pythonRuntimeDepsCheckHook
nixos-test-driver-1.1> Sourcing pypa-install-hook
nixos-test-driver-1.1> Using pypaInstallPhase
nixos-test-driver-1.1> Sourcing python-imports-check-hook.sh
nixos-test-driver-1.1> Using pythonImportsCheckPhase
nixos-test-driver-1.1> Sourcing python-namespaces-hook
nixos-test-driver-1.1> Sourcing python-catch-conflicts-hook.sh
nixos-test-driver-1.1> Running phase: unpackPhase
nixos-test-driver-1.1> unpacking source archive /nix/store/pcpgy2ah5x9vlaqq1yxndks5gybcdd15-src
nixos-test-driver-1.1> source root is src
nixos-test-driver-1.1> setting SOURCE_DATE_EPOCH to timestamp 315619200 of file "src/test_driver/vlan.py"
nixos-test-driver-1.1> Running phase: patchPhase
nixos-test-driver-1.1> Running phase: updateAutotoolsGnuConfigScriptsPhase
nixos-test-driver-1.1> Running phase: configurePhase
nixos-test-driver-1.1> no configure script, doing nothing
nixos-test-driver-1.1> Running phase: buildPhase
nixos-test-driver-1.1> Executing pypaBuildPhase
nixos-test-driver-1.1> Creating a wheel...
nixos-test-driver-1.1> pypa build flags: --no-isolation --outdir dist/ --wheel
nixos-test-driver-1.1> * Getting build dependencies for wheel...
nixos-test-driver-1.1> running egg_info
nixos-test-driver-1.1> creating nixos_test_driver.egg-info
nixos-test-driver-1.1> writing nixos_test_driver.egg-info/PKG-INFO
nixos-test-driver-1.1> writing dependency_links to nixos_test_driver.egg-info/dependency_links.txt
nixos-test-driver-1.1> writing entry points to nixos_test_driver.egg-info/entry_points.txt
nixos-test-driver-1.1> writing top-level names to nixos_test_driver.egg-info/top_level.txt
nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt'
nixos-test-driver-1.1> reading manifest file 'nixos_test_driver.egg-info/SOURCES.txt'
nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt'
nixos-test-driver-1.1> * Building wheel...
nixos-test-driver-1.1> running bdist_wheel
nixos-test-driver-1.1> running build
nixos-test-driver-1.1> running build_py
nixos-test-driver-1.1> creating build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/polling_condition.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/vlan.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/duration.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/logger.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/driver.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/debug.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/errors.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/__init__.py -> build/lib/test_driver
nixos-test-driver-1.1> copying test_driver/efi.py -> build/lib/test_driver
nixos-test-driver-1.1> creating build/lib/test_driver/machine
nixos-test-driver-1.1> copying test_driver/machine/qmp.py -> build/lib/test_driver/machine
nixos-test-driver-1.1> copying test_driver/machine/ocr.py -> build/lib/test_driver/machine
nixos-test-driver-1.1> copying test_driver/machine/__init__.py -> build/lib/test_driver/machine
nixos-test-driver-1.1> running egg_info
nixos-test-driver-1.1> writing nixos_test_driver.egg-info/PKG-INFO
nixos-test-driver-1.1> writing dependency_links to nixos_test_driver.egg-info/dependency_links.txt
nixos-test-driver-1.1> writing entry points to nixos_test_driver.egg-info/entry_points.txt
nixos-test-driver-1.1> writing top-level names to nixos_test_driver.egg-info/top_level.txt
nixos-test-driver-1.1> reading manifest file 'nixos_test_driver.egg-info/SOURCES.txt'
nixos-test-driver-1.1> writing manifest file 'nixos_test_driver.egg-info/SOURCES.txt'
nixos-test-driver-1.1> copying test_driver/py.typed -> build/lib/test_driver
nixos-test-driver-1.1> installing to build/bdist.linux-x86_64/wheel
nixos-test-driver-1.1> running install
nixos-test-driver-1.1> running install_lib
nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel
nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel/test_driver
nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel/test_driver/machine
nixos-test-driver-1.1> copying build/lib/test_driver/machine/qmp.py -> build/bdist.linux-x86_64/wheel/./test_driver/machine
nixos-test-driver-1.1> copying build/lib/test_driver/machine/ocr.py -> build/bdist.linux-x86_64/wheel/./test_driver/machine
nixos-test-driver-1.1> copying build/lib/test_driver/machine/__init__.py -> build/bdist.linux-x86_64/wheel/./test_driver/machine
nixos-test-driver-1.1> copying build/lib/test_driver/polling_condition.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/vlan.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/duration.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/logger.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/driver.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/debug.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/py.typed -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/errors.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/__init__.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> copying build/lib/test_driver/efi.py -> build/bdist.linux-x86_64/wheel/./test_driver
nixos-test-driver-1.1> running install_egg_info
nixos-test-driver-1.1> Copying nixos_test_driver.egg-info to build/bdist.linux-x86_64/wheel/./nixos_test_driver-0.0.0-py3.14.egg-info
nixos-test-driver-1.1> running install_scripts
nixos-test-driver-1.1> creating build/bdist.linux-x86_64/wheel/nixos_test_driver-0.0.0.dist-info/WHEEL
nixos-test-driver-1.1> creating '/build/src/dist/.tmp-i0zvjmce/nixos_test_driver-0.0.0-py3-none-any.whl' and adding 'build/bdist.linux-x86_64/wheel' to it
nixos-test-driver-1.1> adding 'test_driver/__init__.py'
nixos-test-driver-1.1> adding 'test_driver/debug.py'
nixos-test-driver-1.1> adding 'test_driver/driver.py'
nixos-test-driver-1.1> adding 'test_driver/duration.py'
nixos-test-driver-1.1> adding 'test_driver/efi.py'
nixos-test-driver-1.1> adding 'test_driver/errors.py'
nixos-test-driver-1.1> adding 'test_driver/logger.py'
nixos-test-driver-1.1> adding 'test_driver/polling_condition.py'
nixos-test-driver-1.1> adding 'test_driver/py.typed'
nixos-test-driver-1.1> adding 'test_driver/vlan.py'
nixos-test-driver-1.1> adding 'test_driver/machine/__init__.py'
nixos-test-driver-1.1> adding 'test_driver/machine/ocr.py'
nixos-test-driver-1.1> adding 'test_driver/machine/qmp.py'
nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/METADATA'
nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/WHEEL'
nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/entry_points.txt'
nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/top_level.txt'
nixos-test-driver-1.1> adding 'nixos_test_driver-0.0.0.dist-info/RECORD'
nixos-test-driver-1.1> removing build/bdist.linux-x86_64/wheel
nixos-test-driver-1.1> Successfully built nixos_test_driver-0.0.0-py3-none-any.whl
nixos-test-driver-1.1> Finished creating a wheel...
nixos-test-driver-1.1> Finished executing pypaBuildPhase
nixos-test-driver-1.1> Running phase: pythonRuntimeDepsCheckHook
nixos-test-driver-1.1> Executing pythonRuntimeDepsCheck
nixos-test-driver-1.1> Checking runtime dependencies for nixos_test_driver-0.0.0-py3-none-any.whl
nixos-test-driver-1.1> Finished executing pythonRuntimeDepsCheck
nixos-test-driver-1.1> Running phase: installPhase
nixos-test-driver-1.1> Executing pypaInstallPhase
nixos-test-driver-1.1> Successfully installed nixos_test_driver-0.0.0-py3-none-any.whl
nixos-test-driver-1.1> Finished executing pypaInstallPhase
nixos-test-driver-1.1> Running phase: pythonOutputDistPhase
nixos-test-driver-1.1> Executing pythonOutputDistPhase
nixos-test-driver-1.1> Finished executing pythonOutputDistPhase
nixos-test-driver-1.1> Running phase: fixupPhase
nixos-test-driver-1.1> shrinking RPATHs of ELF executables and libraries in /nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1
nixos-test-driver-1.1> checking for references to /build/ in /nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1...
nixos-test-driver-1.1> patching script interpreter paths in /nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1
nixos-test-driver-1.1> stripping (with command strip and flags -S -p) in /nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1/lib /nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1/bin
nixos-test-driver-1.1> shrinking RPATHs of ELF executables and libraries in /nix/store/9sf0zz282m5272sz91mixi2klgc83z2y-nixos-test-driver-1.1-dist
nixos-test-driver-1.1> checking for references to /build/ in /nix/store/9sf0zz282m5272sz91mixi2klgc83z2y-nixos-test-driver-1.1-dist...
nixos-test-driver-1.1> patching script interpreter paths in /nix/store/9sf0zz282m5272sz91mixi2klgc83z2y-nixos-test-driver-1.1-dist
nixos-test-driver-1.1> Rewriting #!/nix/store/3n4qphl9s728sz8frmpqqrv9b1m87g68-python3-3.14.7/bin/python3.14 to #!/nix/store/3n4qphl9s728sz8frmpqqrv9b1m87g68-python3-3.14.7
nixos-test-driver-1.1> wrapping `/nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1/bin/nixos-test-driver'...
nixos-test-driver-1.1> Executing pythonRemoveTestsDir
nixos-test-driver-1.1> Finished executing pythonRemoveTestsDir
nixos-test-driver-1.1> Running phase: installCheckPhase
nixos-test-driver-1.1> ## run ty
nixos-test-driver-1.1> All checks passed!
nixos-test-driver-1.1> ## run ruff check
nixos-test-driver-1.1> All checks passed!
nixos-test-driver-1.1> ## run ruff format
nixos-test-driver-1.1> 25 files already formatted
nixos-test-driver-1.1> Running phase: pythonCatchConflictsPhase
nixos-test-driver-1.1> Running phase: pythonRemoveBinBytecodePhase
nixos-test-driver-1.1> Running phase: pythonImportsCheckPhase
nixos-test-driver-1.1> Executing pythonImportsCheckPhase
building '/nix/store/21spqf76mxf1r44x9zby24f8j468fab2-nixos-test-driver-data-mesher.drv'
nixos-test-driver-data-mesher> Running type check (enable/disable: config.skipTypeCheck)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck
nixos-test-driver-data-mesher> All checks passed!
nixos-test-driver-data-mesher> Linting test script (enable/disable: config.skipLint)
nixos-test-driver-data-mesher> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint
nixos-test-driver-data-mesher> All checks passed!
building '/nix/store/s437iqkg8ds95bhk0qfdrc1riwd9a1f4-container-test-run-data-mesher.drv' on 'ssh-ng://builder@build-x86-01.clan.lol'
building '/nix/store/s437iqkg8ds95bhk0qfdrc1riwd9a1f4-container-test-run-data-mesher.drv'
container-test-run-data-mesher> Machine state will be reset. To keep it, pass --keep-machine-state
container-test-run-data-mesher> start all VLans
container-test-run-data-mesher> (finished: start all VLans, in 0.00 seconds)
container-test-run-data-mesher> 
container-test-run-data-mesher> Test will time out and terminate in 3600.0 seconds
container-test-run-data-mesher> run the VM test script
container-test-run-data-mesher> additionally exposed symbols:
container-test-run-data-mesher>     alpha, beta, gamma,
container-test-run-data-mesher>     vlan1,
container-test-run-data-mesher>     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_ssh
container-test-run-data-mesher> start all VMs
container-test-run-data-mesher> gamma: systemd-nspawn running (pid 54)
container-test-run-data-mesher> beta: systemd-nspawn running (pid 55)
container-test-run-data-mesher> gamma: Waiting for journal at /build/vm-state-gamma/var/log/journal...
container-test-run-data-mesher> beta: Waiting for journal at /build/vm-state-beta/var/log/journal...
container-test-run-data-mesher> alpha: systemd-nspawn running (pid 53)
container-test-run-data-mesher> alpha: Waiting for journal at /build/vm-state-alpha/var/log/journal...
container-test-run-data-mesher> (finished: start all VMs, in 0.00 seconds)
container-test-run-data-mesher> alpha: waiting for unit data-mesher.service
container-test-run-data-mesher> nixos-nspawn(alpha): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-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.
container-test-run-data-mesher> nixos-nspawn(beta): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-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.
container-test-run-data-mesher> nixos-nspawn(gamma): TAP vde-tap1 not found; container will be isolated from VDE
container-test-run-data-mesher> nixos-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.
container-test-run-data-mesher> Note: 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.
container-test-run-data-mesher> Note: 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.
container-test-run-data-mesher> Note: 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.
container-test-run-data-mesher> ░ Spawning container alpha on /build/vm-state-alpha.
container-test-run-data-mesher> ░ Spawning container beta on /build/vm-state-beta.
container-test-run-data-mesher> ░ Spawning container gamma on /build/vm-state-gamma.
container-test-run-data-mesher> beta # [7257700.106849] beta systemd-journald[87]: Journal started
container-test-run-data-mesher> beta # [7257700.106883] beta systemd-journald[87]: Runtime Journal (/run/log/journal/ed33bb42a8194005a9376762b8e6afc6) is 8M, max 3.7G, 3.7G free.
container-test-run-data-mesher> beta # [7257700.111538] beta systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [7257700.112078] beta systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [7257700.112522] beta systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [7257700.117532] beta systemd-journald[87]: Time spent on flushing to /var/log/journal/ed33bb42a8194005a9376762b8e6afc6 is 1.476ms for 5 entries.
container-test-run-data-mesher> beta # [7257700.117532] beta systemd-journald[87]: System Journal (/var/log/journal/ed33bb42a8194005a9376762b8e6afc6) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [7257700.123674] beta systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> beta # [7257700.124181] beta systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [7257700.124237] beta systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [7257700.108663] gamma systemd-journald[88]: Journal started
container-test-run-data-mesher> alpha # [7257700.106667] alpha systemd-journald[87]: Journal started
container-test-run-data-mesher> gamma # [7257700.108700] gamma systemd-journald[88]: Runtime Journal (/run/log/journal/e923a41ed08d4743a4f6192c20cf458e) is 8M, max 3.7G, 3.7G free.
container-test-run-data-mesher> beta # [7257700.124699] beta systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> alpha # [7257700.106696] alpha systemd-journald[87]: Runtime Journal (/run/log/journal/81df5d63470a4b07a56482691836c8f6) is 8M, max 3.7G, 3.7G free.
container-test-run-data-mesher> alpha # [7257700.108745] alpha systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> beta # [7257700.124725] beta systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [7257700.114030] alpha systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> gamma # [7257700.110810] gamma systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
container-test-run-data-mesher> alpha # [7257700.114394] alpha systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [7257700.125199] beta systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> alpha # [7257700.114688] alpha systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [7257700.125214] beta systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [7257700.115219] gamma systemd[1]: Starting Flush Journal to Persistent Storage...
container-test-run-data-mesher> beta # [7257700.206849] beta systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [7257700.206942] beta systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> gamma # [7257700.115563] gamma systemd[1]: Starting Network Name Resolution...
container-test-run-data-mesher> beta # [7257700.207141] beta systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [7257700.118906] alpha systemd-journald[87]: Time spent on flushing to /var/log/journal/81df5d63470a4b07a56482691836c8f6 is 1.734ms for 6 entries.
container-test-run-data-mesher> gamma # [7257700.115890] gamma systemd[1]: Starting Create Static Device Nodes in /dev...
container-test-run-data-mesher> beta # [7257700.207932] beta systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [7257700.118906] alpha systemd-journald[87]: System Journal (/var/log/journal/81df5d63470a4b07a56482691836c8f6) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> beta # [7257700.231176] beta systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> alpha # [7257700.123654] alpha systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> gamma # [7257700.120162] gamma systemd-journald[88]: Time spent on flushing to /var/log/journal/e923a41ed08d4743a4f6192c20cf458e is 1.032ms for 6 entries.
container-test-run-data-mesher> alpha # [7257700.124005] alpha systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> beta # [7257700.232589] beta systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [7257700.124060] alpha systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> gamma # [7257700.120162] gamma systemd-journald[88]: System Journal (/var/log/journal/e923a41ed08d4743a4f6192c20cf458e) is 8M, max 4G, 3.9G free.
container-test-run-data-mesher> alpha # [7257700.124492] alpha systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> beta # [7257700.242290] beta systemd-tmpfiles[198]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.127427] gamma systemd[1]: Finished Create Static Device Nodes in /dev.
container-test-run-data-mesher> alpha # [7257700.124516] alpha systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [7257700.127779] gamma systemd[1]: Reached target Preparation for Local File Systems.
container-test-run-data-mesher> alpha # [7257700.124897] alpha systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> beta # [7257700.242436] beta systemd-tmpfiles[198]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [7257700.124913] alpha systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> gamma # [7257700.127832] gamma systemd[1]: Reached target Local File Systems.
container-test-run-data-mesher> beta # [7257700.242544] beta systemd-tmpfiles[198]: fchmod() of /var/log/journal/ed33bb42a8194005a9376762b8e6afc6 failed: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.128243] gamma systemd[1]: Listening on Boot Loader Control Service Socket.
container-test-run-data-mesher> beta # [7257700.242702] beta systemd-tmpfiles[198]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.128264] gamma systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [7257700.244217] beta systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> alpha # [7257700.206401] alpha systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [7257700.245080] beta systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [7257700.128560] gamma systemd[1]: Starting Save Transient machine-id to Disk...
container-test-run-data-mesher> beta # [7257700.245468] beta systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [7257700.128575] gamma systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys
container-test-run-data-mesher> alpha # [7257700.206525] alpha systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> beta # [7257700.251315] beta systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> gamma # [7257700.204192] gamma systemd[1]: Finished Firewall.
container-test-run-data-mesher> beta # [7257700.257532] beta systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> gamma # [7257700.204285] gamma systemd[1]: Reached target Preparation for Network.
container-test-run-data-mesher> beta # [7257700.258033] beta systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> alpha # [7257700.206688] alpha systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> beta # [7257700.263503] beta systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [7257700.204427] gamma systemd[1]: Listening on Network Management Resolve Hook Socket.
container-test-run-data-mesher> alpha # [7257700.207289] alpha systemd[1]: Starting Network Management...
container-test-run-data-mesher> gamma # [7257700.205072] gamma systemd[1]: Starting Network Management...
container-test-run-data-mesher> alpha # [7257700.231180] alpha systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> beta # [7257700.484991] beta systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [7257700.232038] alpha systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> beta # [7257700.485066] beta systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.231157] gamma systemd[1]: Finished Flush Journal to Persistent Storage.
container-test-run-data-mesher> gamma # [7257700.231637] gamma systemd[1]: Starting Create System Files and Directories...
container-test-run-data-mesher> alpha # [7257700.241426] alpha systemd-tmpfiles[198]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.241528] gamma systemd-tmpfiles[199]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [7257700.241571] alpha systemd-tmpfiles[198]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.241671] gamma systemd-tmpfiles[199]: fchmod() of /var/log/journal failed: Operation not permitted
container-test-run-data-mesher> alpha # [7257700.241673] alpha systemd-tmpfiles[198]: fchmod() of /var/log/journal/81df5d63470a4b07a56482691836c8f6 failed: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.241774] gamma systemd-tmpfiles[199]: fchmod() of /var/log/journal/e923a41ed08d4743a4f6192c20cf458e failed: Operation not permitted
container-test-run-data-mesher> alpha # [7257700.241824] alpha systemd-tmpfiles[198]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.241925] gamma systemd-tmpfiles[199]: fchmod() of /run/log/journal failed: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.242711] gamma systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [7257700.243223] gamma systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [7257700.244115] gamma systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> alpha # [7257700.242613] alpha systemd[1]: Finished Create System Files and Directories.
container-test-run-data-mesher> gamma # [7257700.250796] gamma systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> alpha # [7257700.243155] alpha systemd[1]: Starting Rebuild Journal Catalog...
container-test-run-data-mesher> gamma # [7257700.255955] gamma systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> alpha # [7257700.243481] alpha systemd[1]: Starting Record System Boot/Shutdown in UTMP...
container-test-run-data-mesher> gamma # [7257700.256738] gamma systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> alpha # [7257700.250054] alpha systemd[1]: Finished Record System Boot/Shutdown in UTMP.
container-test-run-data-mesher> gamma # [7257700.261253] gamma systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> alpha # [7257700.256235] alpha systemd[1]: Finished Rebuild Journal Catalog.
container-test-run-data-mesher> gamma # [7257700.476691] gamma systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> alpha # [7257700.256719] alpha systemd[1]: Starting Update is Completed...
container-test-run-data-mesher> gamma # [7257700.476779] gamma systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.482619] gamma systemd-networkd[195]: /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.
container-test-run-data-mesher> alpha # [7257700.261327] alpha systemd[1]: Finished Update is Completed.
container-test-run-data-mesher> gamma # [7257700.482793] gamma systemd-networkd[195]: /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.
container-test-run-data-mesher> alpha # [7257700.473988] alpha systemd-networkd[195]: Failed to increase receive buffer size for general netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.482874] gamma systemd-networkd[195]: lo: Link UP
container-test-run-data-mesher> alpha # [7257700.474077] alpha systemd-networkd[195]: Failed to increase receive buffer size for nftables netlink socket, ignoring: Operation not permitted
container-test-run-data-mesher> gamma # [7257700.482876] gamma systemd-networkd[195]: lo: Gained carrier
container-test-run-data-mesher> gamma # [7257700.483027] gamma systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [7257700.480044] alpha systemd-networkd[195]: /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.
container-test-run-data-mesher> gamma # [7257700.483348] gamma systemd[1]: Started Network Management.
container-test-run-data-mesher> gamma # [7257700.483411] gamma systemd-networkd[195]: eth1: Link UP
container-test-run-data-mesher> alpha # [7257700.480259] alpha systemd-networkd[195]: /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.
container-test-run-data-mesher> alpha # [7257700.480359] alpha systemd-networkd[195]: lo: Link UP
container-test-run-data-mesher> alpha # [7257700.480362] alpha systemd-networkd[195]: lo: Gained carrier
container-test-run-data-mesher> gamma # [7257700.483414] gamma systemd-networkd[195]: eth1: Gained carrier
container-test-run-data-mesher> alpha # [7257700.480550] alpha systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> alpha # [7257700.480853] alpha systemd[1]: Started Network Management.
container-test-run-data-mesher> alpha # [7257700.480912] alpha systemd-networkd[195]: eth1: Link UP
container-test-run-data-mesher> alpha # [7257700.481083] alpha systemd-networkd[195]: eth1: Gained carrier
container-test-run-data-mesher> alpha # [7257700.481719] alpha systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> alpha # [7257700.503636] alpha systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> alpha # [7257700.599395] alpha systemd-resolved[109]: Positive Trust Anchors:
container-test-run-data-mesher> alpha # [7257700.599404] alpha systemd-resolved[109]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> alpha # [7257700.599408] alpha systemd-resolved[109]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> alpha # [7257700.599423] alpha 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 test
container-test-run-data-mesher> alpha # [7257700.609829] alpha systemd-resolved[109]: Using system hostname 'alpha'.
container-test-run-data-mesher> alpha # [7257700.610769] alpha systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> alpha # [7257700.610823] alpha systemd[1]: Reached target Network.
container-test-run-data-mesher> alpha # [7257700.610859] alpha systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> alpha # [7257700.610892] alpha systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> alpha # [7257700.610908] alpha systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> alpha # [7257700.610918] alpha systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> alpha # [7257700.610997] alpha systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> alpha # [7257700.617175] alpha systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> alpha # [7257700.617261] alpha systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> alpha # [7257700.617273] alpha systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> alpha # [7257700.617296] alpha systemd[1]: Reached target Basic System.
container-test-run-data-mesher> alpha # [7257700.618056] alpha systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> alpha # [7257700.618380] alpha systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> alpha # [7257700.618739] alpha systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> alpha # [7257700.619343] alpha systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> alpha # [7257700.646903] alpha systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> alpha # [7257700.717300] alpha nsncd[211]: Aug 23 10:02:38.082 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> alpha # [7257700.717344] alpha systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> alpha # [7257700.717382] alpha systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> alpha # [7257700.717417] alpha systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> alpha # [7257700.718152] alpha systemd[1]: Starting User Login Management...
container-test-run-data-mesher> alpha # [7257700.718552] alpha systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> beta # [7257700.490125] beta systemd-networkd[195]: /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.
container-test-run-data-mesher> beta # [7257700.490269] beta systemd-networkd[195]: /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.
container-test-run-data-mesher> beta # [7257700.490334] beta systemd-networkd[195]: lo: Link UP
container-test-run-data-mesher> beta # [7257700.490339] beta systemd-networkd[195]: lo: Gained carrier
container-test-run-data-mesher> beta # [7257700.490464] beta systemd-networkd[195]: eth1: Configuring with /etc/systemd/network/40-eth1.network.
container-test-run-data-mesher> beta # [7257700.490703] beta systemd[1]: Started Network Management.
container-test-run-data-mesher> beta # [7257700.499121] beta systemd-networkd[195]: eth1: Link UP
container-test-run-data-mesher> beta # [7257700.499161] beta systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> beta # [7257700.499239] beta systemd-networkd[195]: eth1: Gained carrier
container-test-run-data-mesher> beta # [7257700.514074] beta systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> beta # [7257700.608052] beta systemd-resolved[107]: Positive Trust Anchors:
container-test-run-data-mesher> beta # [7257700.608062] beta systemd-resolved[107]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> beta # [7257700.608065] beta systemd-resolved[107]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> beta # [7257700.608082] beta systemd-resolved[107]: 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 test
container-test-run-data-mesher> beta # [7257700.619385] beta systemd-resolved[107]: Using system hostname 'beta'.
container-test-run-data-mesher> beta # [7257700.620350] beta systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> beta # [7257700.620432] beta systemd[1]: Reached target Network.
container-test-run-data-mesher> beta # [7257700.620480] beta systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> beta # [7257700.620524] beta systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> beta # [7257700.620548] beta systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> beta # [7257700.620561] beta systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> beta # [7257700.620660] beta systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> beta # [7257700.620749] beta systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> beta # [7257700.620850] beta systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> beta # [7257700.620868] beta systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> beta # [7257700.620897] beta systemd[1]: Reached target Basic System.
container-test-run-data-mesher> beta # [7257700.638217] beta systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> beta # [7257700.638741] beta systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> beta # [7257700.639261] beta systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> beta # [7257700.639881] beta systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> beta # [7257700.648981] beta systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> beta # [7257700.718322] beta nsncd[211]: Aug 23 10:02:38.083 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> beta # [7257700.718350] beta systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> beta # [7257700.718373] beta systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> beta # [7257700.718401] beta systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> beta # [7257700.718966] beta systemd[1]: Starting User Login Management...
container-test-run-data-mesher> beta # [7257700.719251] beta systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> gamma # [7257700.499161] gamma systemd[1]: Starting Enable Persistent Storage in systemd-networkd...
container-test-run-data-mesher> gamma # [7257700.504604] gamma systemd[1]: Finished Enable Persistent Storage in systemd-networkd.
container-test-run-data-mesher> gamma # [7257700.612461] gamma systemd-resolved[113]: Positive Trust Anchors:
container-test-run-data-mesher> gamma # [7257700.612468] gamma systemd-resolved[113]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
container-test-run-data-mesher> gamma # [7257700.612469] gamma systemd-resolved[113]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
container-test-run-data-mesher> gamma # [7257700.612487] gamma systemd-resolved[113]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
container-test-run-data-mesher> gamma # [7257700.623939] gamma systemd-resolved[113]: Using system hostname 'gamma'.
container-test-run-data-mesher> gamma # [7257700.624753] gamma systemd[1]: Started Network Name Resolution.
container-test-run-data-mesher> gamma # [7257700.624796] gamma systemd[1]: Reached target Network.
container-test-run-data-mesher> gamma # [7257700.624827] gamma systemd[1]: Reached target System Initialization.
container-test-run-data-mesher> gamma # [7257700.624860] gamma systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container
container-test-run-data-mesher> gamma # [7257700.624876] gamma systemd[1]: Started Daily Cleanup of Temporary Directories.
container-test-run-data-mesher> gamma # [7257700.624885] gamma systemd[1]: Reached target Timer Units.
container-test-run-data-mesher> gamma # [7257700.624959] gamma systemd[1]: Listening on D-Bus System Message Bus Socket.
container-test-run-data-mesher> gamma # [7257700.625044] gamma systemd[1]: Listening on Nix Daemon Socket.
container-test-run-data-mesher> gamma # [7257700.625127] gamma systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.
container-test-run-data-mesher> gamma # [7257700.625138] gamma systemd[1]: Reached target Socket Units.
container-test-run-data-mesher> gamma # [7257700.625160] gamma systemd[1]: Reached target Basic System.
container-test-run-data-mesher> gamma # [7257700.638342] gamma systemd[1]: Starting data mesher daemon...
container-test-run-data-mesher> gamma # [7257700.638793] gamma systemd[1]: Starting Import lastlog data into lastlog2 database...
container-test-run-data-mesher> gamma # [7257700.639251] gamma systemd[1]: Starting Name Service Cache Daemon (nsncd)...
container-test-run-data-mesher> gamma # [7257700.639792] gamma systemd[1]: Starting D-Bus System Message Bus...
container-test-run-data-mesher> gamma # [7257700.649339] gamma systemd[1]: Finished Import lastlog data into lastlog2 database.
container-test-run-data-mesher> gamma # [7257700.719380] gamma nsncd[212]: Aug 23 10:02:38.085 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
container-test-run-data-mesher> gamma # [7257700.719469] gamma systemd[1]: Started Name Service Cache Daemon (nsncd).
container-test-run-data-mesher> gamma # [7257700.719526] gamma systemd[1]: Reached target Host and Network Name Lookups.
container-test-run-data-mesher> gamma # [7257700.719567] gamma systemd[1]: Reached target User and Group Name Lookups.
container-test-run-data-mesher> gamma # [7257700.739705] gamma systemd[1]: Starting User Login Management...
container-test-run-data-mesher> gamma # [7257700.740242] gamma systemd[1]: Starting Permit User Sessions...
container-test-run-data-mesher> beta # [7257700.742855] beta systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> beta # [7257700.743323] beta systemd[1]: Started Console Getty.
container-test-run-data-mesher> beta # [7257700.743345] beta systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> beta # [7257700.743357] beta systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> beta # [7257700.787933] beta dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> beta # [7257700.788523] beta dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> beta # [7257700.788523] beta dbus-broker-launch[212]: Invalid user-name in /nix/store/9wh2fdwly2z52z5qljr8wq048axp2x6g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> beta # [7257700.788849] beta systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> beta # [7257700.792685] beta dbus-broker-launch[212]: Ready
container-test-run-data-mesher> beta # [7257700.944897] beta data-mesher[209]: time=2026-08-23T10:02:38.310Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> beta # [7257700.945199] beta data-mesher[209]: time=2026-08-23T10:02:38.310Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE: [/dns/alpha.clan/tcp/7946]} {12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> beta # [7257700.945221] beta data-mesher[209]: time=2026-08-23T10:02:38.310Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> alpha # [7257700.743261] alpha systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> alpha # [7257700.743827] alpha systemd[1]: Started Console Getty.
container-test-run-data-mesher> alpha # [7257700.743853] alpha systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> alpha # [7257700.743864] alpha systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> alpha # [7257700.781427] alpha dbus-broker-launch[212]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> alpha # [7257700.781816] alpha dbus-broker-launch[212]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> alpha # [7257700.781816] alpha dbus-broker-launch[212]: Invalid user-name in /nix/store/9wh2fdwly2z52z5qljr8wq048axp2x6g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> alpha # [7257700.782154] alpha systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> alpha # [7257700.785621] alpha dbus-broker-launch[212]: Ready
container-test-run-data-mesher> alpha # [7257700.945042] alpha data-mesher[209]: time=2026-08-23T10:02:38.310Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> alpha # [7257700.945339] alpha data-mesher[209]: time=2026-08-23T10:02:38.311Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE: [/dns/alpha.clan/tcp/7946]} {12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> alpha # [7257700.945364] alpha data-mesher[209]: time=2026-08-23T10:02:38.311Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> alpha # [7257701.046695] alpha data-mesher[209]: time=2026-08-23T10:02:38.412Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> alpha # [7257701.046755] alpha data-mesher[209]: time=2026-08-23T10:02:38.412Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> gamma # [7257700.745899] gamma systemd[1]: Finished Permit User Sessions.
container-test-run-data-mesher> gamma # [7257700.746347] gamma systemd[1]: Started Console Getty.
container-test-run-data-mesher> gamma # [7257700.746365] gamma systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0
container-test-run-data-mesher> gamma # [7257700.746378] gamma systemd[1]: Reached target Login Prompts.
container-test-run-data-mesher> gamma # [7257700.772402] gamma dbus-broker-launch[213]: Looking up NSS user entry for 'systemd-timesync'...
container-test-run-data-mesher> gamma # [7257700.772841] gamma dbus-broker-launch[213]: NSS returned no entry for 'systemd-timesync'
container-test-run-data-mesher> gamma # [7257700.772841] gamma dbus-broker-launch[213]: Invalid user-name in /nix/store/9wh2fdwly2z52z5qljr8wq048axp2x6g-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
container-test-run-data-mesher> gamma # [7257700.773180] gamma systemd[1]: Started D-Bus System Message Bus.
container-test-run-data-mesher> gamma # [7257700.780469] gamma dbus-broker-launch[213]: Ready
container-test-run-data-mesher> gamma # [7257701.046956] gamma data-mesher[210]: time=2026-08-23T10:02:38.412Z level=DEBUG msg="http config" listen_addresses="[[::1]:7331 127.0.0.1:7331]" port=7331 interfaces=[lo]
container-test-run-data-mesher> gamma # [7257701.047252] gamma data-mesher[210]: time=2026-08-23T10:02:38.412Z level=DEBUG msg="cluster config" port=7946 interfaces=[] bootstrap_peers="[{12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE: [/dns/alpha.clan/tcp/7946]} {12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k: [/dns/beta.clan/tcp/7946]}]" push_pull_interval=5s peer_id=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> gamma # [7257701.047271] gamma data-mesher[210]: time=2026-08-23T10:02:38.412Z level=INFO msg="config loaded" config_file=/etc/data-mesher/dm.toml
container-test-run-data-mesher> gamma # [7257701.049292] gamma data-mesher[210]: time=2026-08-23T10:02:38.414Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> gamma # [7257701.049373] gamma data-mesher[210]: time=2026-08-23T10:02:38.415Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> gamma # [7257701.051676] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="libp2p host created" peer_id=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 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]"
container-test-run-data-mesher> gamma # [7257701.051726] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> gamma # [7257701.051726] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> gamma # [7257701.051726] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> gamma # [7257701.051726] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="starting server"
container-test-run-data-mesher> gamma # [7257701.051795] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> gamma # [7257701.051820] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> gamma # [7257701.051835] gamma data-mesher[210]: time=2026-08-23T10:02:38.417Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> gamma # [7257701.053785] gamma data-mesher[210]: time=2026-08-23T10:02:38.419Z level=INFO msg="peer connected" peer_id=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> gamma # [7257701.056345] gamma data-mesher[210]: time=2026-08-23T10:02:38.422Z level=INFO msg="peer connected" peer_id=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> gamma # [7257701.062638] gamma systemd-logind[229]: New seat seat0.
container-test-run-data-mesher> gamma # [7257701.062754] gamma systemd[1]: Started User Login Management.
container-test-run-data-mesher> gamma # [7257701.081243] gamma systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> gamma # [7257701.087838] gamma systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> gamma # [7257701.087878] gamma systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> gamma # [7257701.227803] gamma systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> gamma # [7257701.228467] gamma systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> alpha # [7257701.048991] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="libp2p host created" peer_id=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE 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]"
container-test-run-data-mesher> alpha # [7257701.049038] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> alpha # [7257701.049038] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> alpha # [7257701.049038] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> alpha # [7257701.049038] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="starting server"
container-test-run-data-mesher> alpha # [7257701.049140] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> alpha # [7257701.049140] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> alpha # [7257701.049167] alpha data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> alpha # [7257701.051396] alpha data-mesher[209]: time=2026-08-23T10:02:38.417Z level=INFO msg="peer connected" peer_id=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k remote_addr=/ip4/192.168.1.2/tcp/7946
container-test-run-data-mesher> alpha # [7257701.051974] alpha data-mesher[209]: time=2026-08-23T10:02:38.417Z level=INFO msg="peer connected" peer_id=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k remote_addr=/ip4/192.168.1.2/tcp/45538
container-test-run-data-mesher> alpha # [7257701.056542] alpha data-mesher[209]: time=2026-08-23T10:02:38.422Z level=INFO msg="peer connected" peer_id=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> alpha # [7257701.059727] alpha systemd-logind[228]: New seat seat0.
container-test-run-data-mesher> alpha # [7257701.059873] alpha systemd[1]: Started User Login Management.
container-test-run-data-mesher> alpha # [7257701.060703] alpha systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> alpha # [7257701.086185] alpha systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> alpha # [7257701.086293] alpha systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> alpha # [7257701.227870] alpha systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> alpha # [7257701.228600] alpha systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> beta # [7257701.047124] beta data-mesher[209]: time=2026-08-23T10:02:38.412Z level=INFO msg="checking file integrity"
container-test-run-data-mesher> beta # [7257701.047221] beta data-mesher[209]: time=2026-08-23T10:02:38.412Z level=INFO msg="file integrity check complete"
container-test-run-data-mesher> beta # [7257701.049266] beta data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="libp2p host created" peer_id=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k 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]"
container-test-run-data-mesher> beta # [7257701.049332] beta data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="registered HTTP route" method=GET path=/files
container-test-run-data-mesher> beta # [7257701.049332] beta data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="registered HTTP route" method=PUT path=/files/:name
container-test-run-data-mesher> beta # [7257701.049332] beta data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="registered HTTP route" method=DELETE path=/files/:name
container-test-run-data-mesher> beta # [7257701.049332] beta data-mesher[209]: time=2026-08-23T10:02:38.414Z level=INFO msg="starting server"
container-test-run-data-mesher> beta # [7257701.049376] beta data-mesher[209]: time=2026-08-23T10:02:38.415Z level=INFO msg="waiting for DHT to populate" delay=10s
container-test-run-data-mesher> beta # [7257701.049419] beta data-mesher[209]: time=2026-08-23T10:02:38.415Z level=INFO msg="HTTP server listening" address=[::1]:7331
container-test-run-data-mesher> beta # [7257701.049444] beta data-mesher[209]: time=2026-08-23T10:02:38.415Z level=INFO msg="HTTP server listening" address=127.0.0.1:7331
container-test-run-data-mesher> beta # [7257701.051714] beta data-mesher[209]: time=2026-08-23T10:02:38.417Z level=INFO msg="peer connected" peer_id=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [7257701.051930] beta data-mesher[209]: time=2026-08-23T10:02:38.417Z level=INFO msg="peer connected" peer_id=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE remote_addr=/ip4/192.168.1.1/tcp/7946
container-test-run-data-mesher> beta # [7257701.054243] beta data-mesher[209]: time=2026-08-23T10:02:38.419Z level=INFO msg="peer connected" peer_id=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 remote_addr=/ip4/192.168.1.3/tcp/7946
container-test-run-data-mesher> beta # [7257701.059881] beta systemd-logind[228]: New seat seat0.
container-test-run-data-mesher> beta # [7257701.060024] beta systemd[1]: Started User Login Management.
container-test-run-data-mesher> beta # [7257701.060703] beta systemd[1]: Starting linger-users.service...
container-test-run-data-mesher> beta # [7257701.086207] beta systemd[1]: linger-users.service: Deactivated successfully.
container-test-run-data-mesher> beta # [7257701.086304] beta systemd[1]: Finished linger-users.service.
container-test-run-data-mesher> beta # [7257701.227820] beta systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
container-test-run-data-mesher> beta # [7257701.228469] beta systemd[1]: Finished Save Transient machine-id to Disk.
container-test-run-data-mesher> gamma # [7257701.566069] gamma systemd-networkd[195]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha # [7257701.885109] alpha systemd-networkd[195]: eth1: Gained IPv6LL
container-test-run-data-mesher> beta # [7257702.397058] beta systemd-networkd[195]: eth1: Gained IPv6LL
container-test-run-data-mesher> alpha: still waiting for container 'alpha' to reach ready state...
container-test-run-data-mesher> alpha: (finished: waiting for unit data-mesher.service, in 11.65 seconds)
container-test-run-data-mesher> beta: waiting for unit data-mesher.service
container-test-run-data-mesher> beta: (finished: waiting for unit data-mesher.service, in 0.01 seconds)
container-test-run-data-mesher> gamma: waiting for unit data-mesher.service
container-test-run-data-mesher> gamma: (finished: waiting for unit data-mesher.service, in 0.01 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'hello world' > /tmp/test_file
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'hello world' > /tmp/test_file, in 0.00 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/test_file --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.01 seconds)
container-test-run-data-mesher> ??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-data-mesher>     File "/nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-data-mesher> alpha: 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
container-test-run-data-mesher> ??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.
container-test-run-data-mesher>     File "/nix/store/mrjf3yqmbx2a02sn8gb6lax4ky8bpi7j-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 39
container-test-run-data-mesher> alpha: (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.00 seconds)
container-test-run-data-mesher> beta: 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
container-test-run-data-mesher> gamma # [7257711.052230] gamma data-mesher[210]: time=2026-08-23T10:02:48.417Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> gamma # [7257711.052230] gamma data-mesher[210]: time=2026-08-23T10:02:48.417Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> gamma # [7257711.052573] gamma data-mesher[210]: time=2026-08-23T10:02:48.418Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> gamma # [7257711.052573] gamma data-mesher[210]: time=2026-08-23T10:02:48.418Z level=INFO msg="state exchange complete" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> gamma # [7257711.052573] gamma data-mesher[210]: time=2026-08-23T10:02:48.418Z level=INFO msg="server started"
container-test-run-data-mesher> gamma # [7257711.052660] gamma data-mesher[210]: time=2026-08-23T10:02:48.418Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> gamma # [7257711.052684] gamma systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> gamma # [7257711.052833] gamma systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> gamma # [7257711.058043] gamma systemd[1]: Startup finished in 11.298s.
container-test-run-data-mesher> alpha # [7257711.051213] alpha data-mesher[209]: time=2026-08-23T10:02:48.416Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> alpha # [7257711.051213] alpha data-mesher[209]: time=2026-08-23T10:02:48.416Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257711.051719] alpha data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="received state sync from peer" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257711.051719] alpha data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257711.051719] alpha data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257711.051867] alpha data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257711.051867] alpha data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="server started"
container-test-run-data-mesher> alpha # [7257711.051867] alpha data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> alpha # [7257711.051969] alpha systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> alpha # [7257711.052183] alpha systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> alpha # [7257711.052484] alpha data-mesher[209]: time=2026-08-23T10:02:48.418Z level=INFO msg="received state sync from peer" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> alpha # [7257711.052484] alpha data-mesher[209]: time=2026-08-23T10:02:48.418Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> alpha # [7257711.058034] alpha systemd[1]: Startup finished in 11.303s.
container-test-run-data-mesher> alpha # [7257711.162087] alpha data-mesher[209]: time=2026-08-23T10:02:48.527Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> beta # [7257711.051485] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="performing state exchange with peers on join" count=1
container-test-run-data-mesher> beta # [7257711.051485] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257711.051810] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="received state sync from peer" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257711.051810] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257711.051810] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257711.051810] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="state exchange complete" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257711.051810] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="server started"
container-test-run-data-mesher> beta # [7257711.051877] beta data-mesher[209]: time=2026-08-23T10:02:48.417Z level=INFO msg="starting expired-file sweeper" interval=1m0s
container-test-run-data-mesher> beta # [7257711.051964] beta systemd[1]: Started data mesher daemon.
container-test-run-data-mesher> beta # [7257711.052165] beta systemd[1]: Reached target Multi-User System.
container-test-run-data-mesher> beta # [7257711.052278] beta systemd[1]: Startup finished in 11.293s.
container-test-run-data-mesher> beta: (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.02 seconds)
container-test-run-data-mesher> gamma: 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
container-test-run-data-mesher> gamma # [7257716.055046] gamma data-mesher[210]: time=2026-08-23T10:02:53.420Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [7257716.055046] gamma data-mesher[210]: time=2026-08-23T10:02:53.420Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257716.055390] gamma data-mesher[210]: time=2026-08-23T10:02:53.420Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257716.055390] gamma data-mesher[210]: time=2026-08-23T10:02:53.420Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257716.055390] gamma data-mesher[210]: time=2026-08-23T10:02:53.421Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [7257716.053279] alpha data-mesher[209]: time=2026-08-23T10:02:53.418Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [7257716.053651] alpha data-mesher[209]: time=2026-08-23T10:02:53.418Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257716.053769] alpha data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="received state sync from peer" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257716.053769] alpha data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257716.053769] alpha data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257716.053769] alpha data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257716.053859] alpha data-mesher[209]: time=2026-08-23T10:02:53.419Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [7257716.053978] alpha data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="received file request" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_file
container-test-run-data-mesher> alpha # [7257716.055070] alpha data-mesher[209]: time=2026-08-23T10:02:53.420Z level=INFO msg="file transfer complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_file
container-test-run-data-mesher> beta # [7257716.053583] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [7257716.053583] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="received state sync from peer" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=DEBUG msg="new file detected" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE name=test_file name=test_file
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-23 10:02:48.526 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=DEBUG msg="new file detected" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE name=test_file name=test_file
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=INFO msg="state exchange complete" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257716.053937] beta data-mesher[209]: time=2026-08-23T10:02:53.419Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [7257716.055223] beta data-mesher[209]: time=2026-08-23T10:02:53.420Z level=INFO msg="received state sync from peer" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257716.055223] beta data-mesher[209]: time=2026-08-23T10:02:53.420Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257716.061004] beta data-mesher[209]: time=2026-08-23T10:02:53.426Z level=INFO msg="download complete" name=test_file signed_at="2026-08-23 10:02:48.526 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE written=true elapsed=7.220357ms
container-test-run-data-mesher> gamma: (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.02 seconds)
container-test-run-data-mesher> beta: waiting for success: data-mesher file delete test_file --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key
container-test-run-data-mesher> beta: (finished: waiting for success: data-mesher file delete test_file --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub --url http://[::1]:7331 --key /nix/store/xysmjnpbvgzv55l8s8ixr5y3ih9fz7qc-admin.key, in 0.02 seconds)
container-test-run-data-mesher> alpha: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma # [7257721.055399] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [7257721.055399] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257721.055680] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257721.055680] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=DEBUG msg="new file detected" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k name=test_file name=test_file
container-test-run-data-mesher> gamma # [7257721.055680] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257721.055733] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [7257721.055733] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=INFO msg="scheduling file download" name=test_file
container-test-run-data-mesher> gamma # [7257721.055771] gamma data-mesher[210]: time=2026-08-23T10:02:58.421Z level=INFO msg="downloading file" name=test_file signed_at="2026-08-23 10:02:48.526 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257721.061157] gamma data-mesher[210]: time=2026-08-23T10:02:58.426Z level=INFO msg="download complete" name=test_file signed_at="2026-08-23 10:02:48.526 +0000 UTC" signed_by="azwT+VhTxA+BF73Hwq0uqdXHG8XvHU2BknoVXgmEjww=" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k written=true elapsed=5.396773ms
container-test-run-data-mesher> alpha # [7257721.054165] alpha data-mesher[209]: time=2026-08-23T10:02:58.419Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [7257721.054444] alpha data-mesher[209]: time=2026-08-23T10:02:58.419Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257721.054692] alpha data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257721.054707] alpha data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257721.054729] alpha data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="received state sync from peer" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257721.054729] alpha data-mesher[209]: time=2026-08-23T10:02:58.420Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [7257721.054780] alpha data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> beta # [7257721.054427] beta data-mesher[209]: time=2026-08-23T10:02:58.420Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [7257721.054427] beta data-mesher[209]: time=2026-08-23T10:02:58.420Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257721.054767] beta data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="received state sync from peer" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257721.054767] beta data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257721.054849] beta data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257721.054860] beta data-mesher[209]: time=2026-08-23T10:02:58.420Z level=INFO msg="state exchange complete" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257721.054880] beta data-mesher[209]: time=2026-08-23T10:02:58.420Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [7257721.055536] beta data-mesher[209]: time=2026-08-23T10:02:58.421Z level=INFO msg="received state sync from peer" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257721.055550] beta data-mesher[209]: time=2026-08-23T10:02:58.421Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257721.055983] beta data-mesher[209]: time=2026-08-23T10:02:58.421Z level=INFO msg="received file request" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_file
container-test-run-data-mesher> beta # [7257721.056723] beta data-mesher[209]: time=2026-08-23T10:02:58.422Z level=INFO msg="file transfer complete" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_file
container-test-run-data-mesher> beta # [7257721.223460] beta data-mesher[209]: time=2026-08-23T10:02:58.589Z level=INFO msg=http_request uri=/files/test_file status=204
container-test-run-data-mesher> gamma # [7257726.055561] gamma data-mesher[210]: time=2026-08-23T10:03:03.421Z level=INFO msg="received state sync from peer" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> gamma # [7257726.055561] gamma data-mesher[210]: time=2026-08-23T10:03:03.421Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> gamma # [7257726.055837] gamma data-mesher[210]: time=2026-08-23T10:03:03.421Z level=INFO msg="received state sync from peer" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257726.055837] gamma data-mesher[210]: time=2026-08-23T10:03:03.421Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257726.056742] gamma data-mesher[210]: time=2026-08-23T10:03:03.422Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [7257726.056765] gamma data-mesher[210]: time=2026-08-23T10:03:03.422Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> gamma # [7257726.057362] gamma data-mesher[210]: time=2026-08-23T10:03:03.423Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> gamma # [7257726.057389] gamma data-mesher[210]: time=2026-08-23T10:03:03.423Z level=INFO msg="state exchange complete" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> gamma # [7257726.057389] gamma data-mesher[210]: time=2026-08-23T10:03:03.423Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [7257726.073398] gamma data-mesher[210]: time=2026-08-23T10:03:03.439Z level=DEBUG msg="imported tombstone" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k name=test_file written=true
container-test-run-data-mesher> alpha # [7257726.055248] alpha data-mesher[209]: time=2026-08-23T10:03:03.420Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [7257726.055248] alpha data-mesher[209]: time=2026-08-23T10:03:03.420Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> alpha # [7257726.055627] alpha data-mesher[209]: time=2026-08-23T10:03:03.421Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> alpha # [7257726.055661] alpha data-mesher[209]: time=2026-08-23T10:03:03.421Z level=INFO msg="state exchange complete" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> alpha # [7257726.055661] alpha data-mesher[209]: time=2026-08-23T10:03:03.421Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [7257726.057186] alpha data-mesher[209]: time=2026-08-23T10:03:03.422Z level=INFO msg="received state sync from peer" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> alpha # [7257726.057186] alpha data-mesher[209]: time=2026-08-23T10:03:03.422Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257726.055198] beta data-mesher[209]: time=2026-08-23T10:03:03.420Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [7257726.055540] beta data-mesher[209]: time=2026-08-23T10:03:03.420Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> beta # [7257726.073493] beta data-mesher[209]: time=2026-08-23T10:03:03.439Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257726.073527] beta data-mesher[209]: time=2026-08-23T10:03:03.439Z level=INFO msg="state exchange complete" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> beta # [7257726.073527] beta data-mesher[209]: time=2026-08-23T10:03:03.439Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 10.04 seconds)
container-test-run-data-mesher> beta: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> beta: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.00 seconds)
container-test-run-data-mesher> gamma: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file
container-test-run-data-mesher> gamma: (finished: waiting for success: test ! -f /var/lib/data-mesher/files/home/test_file, in 0.00 seconds)
container-test-run-data-mesher> alpha: must succeed: cat /nix/store/9kszzn4f93gc4ja33khxa597xviz2q9y-per-machine-alpha-data-mesher-node-identity_identity.pub
container-test-run-data-mesher> alpha: (finished: must succeed: cat /nix/store/9kszzn4f93gc4ja33khxa597xviz2q9y-per-machine-alpha-data-mesher-node-identity_identity.pub, in 0.00 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'namespace_data' > /tmp/ns_file
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'namespace_data' > /tmp/ns_file, in 0.00 seconds)
container-test-run-data-mesher> alpha: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert
container-test-run-data-mesher> alpha: (finished: waiting for success: data-mesher file update /tmp/ns_file --name 'test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub --cert /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.cert, in 0.01 seconds)
container-test-run-data-mesher> alpha: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> alpha: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE, in 0.00 seconds)
container-test-run-data-mesher> beta: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> gamma # [7257731.058236] gamma data-mesher[210]: time=2026-08-23T10:03:08.423Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [7257731.058236] gamma data-mesher[210]: time=2026-08-23T10:03:08.423Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257731.058845] gamma data-mesher[210]: time=2026-08-23T10:03:08.424Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257731.058845] gamma data-mesher[210]: time=2026-08-23T10:03:08.424Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257731.058845] gamma data-mesher[210]: time=2026-08-23T10:03:08.424Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [7257731.075387] gamma data-mesher[210]: time=2026-08-23T10:03:08.441Z level=INFO msg="received state sync from peer" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257731.075387] gamma data-mesher[210]: time=2026-08-23T10:03:08.441Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257731.055883] alpha data-mesher[209]: time=2026-08-23T10:03:08.421Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [7257731.055883] alpha data-mesher[209]: time=2026-08-23T10:03:08.421Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257731.056614] alpha data-mesher[209]: time=2026-08-23T10:03:08.422Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257731.062240] alpha data-mesher[209]: time=2026-08-23T10:03:08.427Z level=DEBUG msg="imported tombstone" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k name=test_file written=true
container-test-run-data-mesher> alpha # [7257731.062240] alpha data-mesher[209]: time=2026-08-23T10:03:08.427Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> alpha # [7257731.062363] alpha data-mesher[209]: time=2026-08-23T10:03:08.427Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [7257731.285241] alpha data-mesher[209]: time=2026-08-23T10:03:08.650Z level=INFO msg=http_request uri=/files/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE status=204
container-test-run-data-mesher> beta # [7257731.056264] beta data-mesher[209]: time=2026-08-23T10:03:08.421Z level=INFO msg="received state sync from peer" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257731.056264] beta data-mesher[209]: time=2026-08-23T10:03:08.421Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257731.058530] beta data-mesher[209]: time=2026-08-23T10:03:08.424Z level=INFO msg="received state sync from peer" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257731.058530] beta data-mesher[209]: time=2026-08-23T10:03:08.424Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257731.075065] beta data-mesher[209]: time=2026-08-23T10:03:08.440Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [7257731.075150] beta data-mesher[209]: time=2026-08-23T10:03:08.440Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> beta # [7257731.075502] beta data-mesher[209]: time=2026-08-23T10:03:08.441Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257731.075561] beta data-mesher[209]: time=2026-08-23T10:03:08.441Z level=INFO msg="state exchange complete" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> beta # [7257731.075561] beta data-mesher[209]: time=2026-08-23T10:03:08.441Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE, in 5.02 seconds)
container-test-run-data-mesher> gamma: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> gamma: (finished: waiting for success: test -f /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE && grep -q 'namespace_data' /var/lib/data-mesher/files/home/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE, in 0.00 seconds)
container-test-run-data-mesher> alpha: must succeed: echo -n 'no_cert' > /tmp/ns_nocert
container-test-run-data-mesher> alpha: (finished: must succeed: echo -n 'no_cert' > /tmp/ns_nocert, in 0.00 seconds)
container-test-run-data-mesher> alpha: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub
container-test-run-data-mesher> Error: failed to update file: 403 Forbidden, signer 6wxjGhC6Ud70+1a4h9kxHIGMO+RNSbo6Hr8lJVpVxOE= is not authorized for this file test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> alpha # [7257736.063299] alpha data-mesher[209]: time=2026-08-23T10:03:13.428Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> alpha # [7257736.063555] alpha data-mesher[209]: time=2026-08-23T10:03:13.428Z level=DEBUG msg="initiating state exchange" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> alpha # [7257736.063725] alpha data-mesher[209]: time=2026-08-23T10:03:13.429Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> alpha # [7257736.063747] alpha data-mesher[209]: time=2026-08-23T10:03:13.429Z level=INFO msg="state exchange complete" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 timeout=5s
container-test-run-data-mesher> alpha # [7257736.063760] alpha data-mesher[209]: time=2026-08-23T10:03:13.429Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> alpha # [7257736.063869] alpha data-mesher[209]: time=2026-08-23T10:03:13.429Z level=INFO msg="received file request" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> alpha # [7257736.063998] alpha data-mesher[209]: time=2026-08-23T10:03:13.429Z level=INFO msg="file transfer complete" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6 network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> alpha # [7257736.076184] alpha data-mesher[209]: time=2026-08-23T10:03:13.441Z level=INFO msg="received state sync from peer" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257736.076184] alpha data-mesher[209]: time=2026-08-23T10:03:13.441Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> alpha # [7257736.076791] alpha data-mesher[209]: time=2026-08-23T10:03:13.442Z level=INFO msg="received file request" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> alpha # [7257736.077560] alpha data-mesher[209]: time=2026-08-23T10:03:13.443Z level=INFO msg="file transfer complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k network="2g+VE8rllNIfhbJZmiP65vDPQpWj0uSnHmB3p6la9QQ=" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> alpha # [7257736.331496] alpha data-mesher[209]: time=2026-08-23T10:03:13.697Z level=INFO msg=http_request uri=/files/test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE status=403
container-test-run-data-mesher> alpha: (finished: must fail: data-mesher file update /tmp/ns_nocert --name 'test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE' --url http://[::1]:7331 --key /run/secrets/per-machine/alpha/data-mesher-node-identity/identity.key --network-id /nix/store/md1pcbjn1v89hs7carisv8y9ir35lfwf-shared-data-mesher-network_network.pub, in 0.01 seconds)
container-test-run-data-mesher> (finished: run the VM test script, in 36.85 seconds)
container-test-run-data-mesher> gamma # [7257736.059295] gamma data-mesher[210]: time=2026-08-23T10:03:13.424Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> gamma # [7257736.059295] gamma data-mesher[210]: time=2026-08-23T10:03:13.424Z level=DEBUG msg="initiating state exchange" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257736.059708] gamma data-mesher[210]: time=2026-08-23T10:03:13.425Z level=INFO msg="merging remote state" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k
container-test-run-data-mesher> gamma # [7257736.059726] gamma data-mesher[210]: time=2026-08-23T10:03:13.425Z level=INFO msg="state exchange complete" peer=12D3KooWSJUZDcenbwM31J2VwTwpuUnPajinX17KHgnMRrBvpU3k timeout=5s
container-test-run-data-mesher> gamma # [7257736.059739] gamma data-mesher[210]: time=2026-08-23T10:03:13.425Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> gamma # [7257736.063549] gamma data-mesher[210]: time=2026-08-23T10:03:13.429Z level=INFO msg="received state sync from peer" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> gamma # [7257736.063549] gamma data-mesher[210]: time=2026-08-23T10:03:13.429Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> gamma # [7257736.063652] gamma data-mesher[210]: time=2026-08-23T10:03:13.429Z level=DEBUG msg="new file detected" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE name=test_file name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> gamma # [7257736.063726] gamma data-mesher[210]: time=2026-08-23T10:03:13.429Z level=INFO msg="scheduling file download" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> gamma # [7257736.063758] gamma data-mesher[210]: time=2026-08-23T10:03:13.429Z level=INFO msg="downloading file" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE signed_at="2026-08-23 10:03:08.649 +0000 UTC" signed_by="6wxjGhC6Ud70+1a4h9kxHIGMO+RNSbo6Hr8lJVpVxOE=" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> gamma # [7257736.064479] gamma data-mesher[210]: time=2026-08-23T10:03:13.430Z level=INFO msg="download complete" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE signed_at="2026-08-23 10:03:08.649 +0000 UTC" signed_by="6wxjGhC6Ud70+1a4h9kxHIGMO+RNSbo6Hr8lJVpVxOE=" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE written=true elapsed=739.983µs
container-test-run-data-mesher> beta # [7257736.059636] beta data-mesher[209]: time=2026-08-23T10:03:13.425Z level=INFO msg="received state sync from peer" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257736.059636] beta data-mesher[209]: time=2026-08-23T10:03:13.425Z level=INFO msg="merging remote state" peer=12D3KooWKK524SuTczFuSoymNmpvXXu4Je5fruwAtvEQ7dni5pT6
container-test-run-data-mesher> beta # [7257736.076023] beta data-mesher[209]: time=2026-08-23T10:03:13.441Z level=DEBUG msg="attempting push/pull" peer_count=2
container-test-run-data-mesher> beta # [7257736.076023] beta data-mesher[209]: time=2026-08-23T10:03:13.441Z level=DEBUG msg="initiating state exchange" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257736.076417] beta data-mesher[209]: time=2026-08-23T10:03:13.442Z level=INFO msg="merging remote state" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257736.076512] beta data-mesher[209]: time=2026-08-23T10:03:13.442Z level=DEBUG msg="new file detected" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE name=test_file name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> beta # [7257736.076512] beta data-mesher[209]: time=2026-08-23T10:03:13.442Z level=INFO msg="state exchange complete" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE timeout=5s
container-test-run-data-mesher> beta # [7257736.076573] beta data-mesher[209]: time=2026-08-23T10:03:13.442Z level=DEBUG msg="push/pull successful" interval=5s
container-test-run-data-mesher> beta # [7257736.076573] beta data-mesher[209]: time=2026-08-23T10:03:13.442Z level=INFO msg="scheduling file download" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE
container-test-run-data-mesher> beta # [7257736.076616] beta data-mesher[209]: time=2026-08-23T10:03:13.442Z level=INFO msg="downloading file" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE signed_at="2026-08-23 10:03:08.649 +0000 UTC" signed_by="6wxjGhC6Ud70+1a4h9kxHIGMO+RNSbo6Hr8lJVpVxOE=" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE
container-test-run-data-mesher> beta # [7257736.077993] beta data-mesher[209]: time=2026-08-23T10:03:13.443Z level=INFO msg="download complete" name=test_ns/6wxjGhC6Ud70-1a4h9kxHIGMO-RNSbo6Hr8lJVpVxOE signed_at="2026-08-23 10:03:08.649 +0000 UTC" signed_by="6wxjGhC6Ud70+1a4h9kxHIGMO+RNSbo6Hr8lJVpVxOE=" peer=12D3KooWRdtw4ufEkCt2qnkRpXf7cmbLvriEAsjkJg9CH5pfyKXE written=true elapsed=1.404635ms
container-test-run-data-mesher> test script finished in 37.26s
container-test-run-data-mesher> cleanup
container-test-run-data-mesher> kill NspawnMachine (pid 53)
container-test-run-data-mesher> kill NspawnMachine (pid 55)
container-test-run-data-mesher> kill NspawnMachine (pid 54)
container-test-run-data-mesher> Container alpha terminated by signal KILL.
container-test-run-data-mesher> Container beta terminated by signal KILL.
container-test-run-data-mesher> Container gamma terminated by signal KILL.
container-test-run-data-mesher> (finished: cleanup, in 0.29 seconds)

post-build step Upload to niks3: ok
time=2026-08-23T10:03:14.899Z level=INFO msg="Uploading 0 paths to niks3.clan.lol (1 already cached)"
time=2026-08-23T10:03:15.177Z level=INFO msg="Uploading 1 narinfos"
time=2026-08-23T10:03:15.517Z level=INFO msg="Upload complete. (713ms)"