2025-12-04 14:19:23,771 - INFO - ya.test.2836152  - pytest_runtest_setup: ####################################################################################################
2025-12-04 14:19:23,783 - INFO - ya.test.2836152  - pytest_runtest_setup: test_when_deactivate_fat_tenant_creation_another_tenant_is_ok[enable_alter_database_create_hive_first--false]
2025-12-04 14:19:23,783 - INFO - ya.test.2836152  - pytest_runtest_setup: ####################################################################################################
2025-12-04 14:19:23,783 - INFO - ya.test.2836152  - pytest_runtest_setup: Test setup
2025-12-04 14:19:23,784 - INFO - ydb.tests.library.fixtures.2836152  - ydb_cluster: destroy ydb_cluster for __tests__.test_tenants
2025-12-04 14:19:26,787 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:19973/1
2025-12-04 14:19:26,817 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12055
2025-12-04 14:19:26,818 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15503
2025-12-04 14:19:26,818 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10092
2025-12-04 14:19:26,819 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3538
2025-12-04 14:19:26,824 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10351
2025-12-04 14:19:26,847 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10064
2025-12-04 14:19:26,847 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20204
2025-12-04 14:19:26,848 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7200
2025-12-04 14:19:26,848 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2714
2025-12-04 14:19:26,848 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62724
2025-12-04 14:19:26,848 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20456
2025-12-04 14:19:26,848 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9384
2025-12-04 14:19:26,849 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5036
2025-12-04 14:19:26,849 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8399
2025-12-04 14:19:26,849 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4093
2025-12-04 14:19:26,849 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22644
2025-12-04 14:19:26,849 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25301
2025-12-04 14:19:26,850 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23223
2025-12-04 14:19:26,850 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22881
2025-12-04 14:19:26,850 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15900
2025-12-04 14:19:26,876 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12438
2025-12-04 14:19:26,882 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14201
2025-12-04 14:19:26,902 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24822
2025-12-04 14:19:26,903 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7027
2025-12-04 14:19:26,903 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15558
2025-12-04 14:19:26,903 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22873
2025-12-04 14:19:26,903 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23851
2025-12-04 14:19:26,904 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1064
2025-12-04 14:19:26,908 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19973
2025-12-04 14:19:26,914 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21427
2025-12-04 14:19:27,017 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:19:27,018 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8741
2025-12-04 14:19:27,019 - INFO - ydb.tests.library.fixtures.2836152  - ydb_cluster: setup ydb_cluster for __tests__.test_tenants
2025-12-04 14:19:27,049 - INFO - ydb.tests.library.fixtures.2836152  - ydb_cluster: setup ydb_cluster as local
2025-12-04 14:19:27,055 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - start: Working directory: /home/runner/.ya/build/build_root/l7pa/002795/r3tmp/kikimr_cluster_cjidiif7
2025-12-04 14:19:27,167 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:19:27,168 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1915
2025-12-04 14:19:27,178 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26135
2025-12-04 14:19:27,180 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29665
2025-12-04 14:19:27,207 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8948
2025-12-04 14:19:27,208 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:19:27,209 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/node_1/logfile_gljzksfy.log --grpc-port=1915 --mon-port=26135 --ic-port=8741
2025-12-04 14:19:27,209 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:1915/1, disk_size 68719476736
2025-12-04 14:19:27,209 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:19:27,223 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/node_1/logfile_gljzksfy.log --grpc-port=1915 --mon-port=26135 --ic-port=8741
2025-12-04 14:19:27,224 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/node_1/logfile_gljzksfy.log', '--grpc-port=1915', '--mon-port=26135', '--ic-port=8741')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/node_1' (no wait)
2025-12-04 14:19:27,245 - DEBUG - ya.test.2836152  - execute: Command pid: 2984415
2025-12-04 14:19:27,246 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:1915/1
2025-12-04 14:19:27,271 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.23
2025-12-04 14:19:27,273 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.23
2025-12-04 14:19:27,275 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:19:27,277 - DEBUG - ya.test.2836152  - execute: Command pid: 2984457
2025-12-04 14:19:27,850 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) rc: 0
2025-12-04 14:19:27,851 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) elapsed time (sec): 0.5372228622436523
2025-12-04 14:19:27,851 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) idrss: 0
2025-12-04 14:19:27,851 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) inblock: 0
2025-12-04 14:19:27,852 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) isrss: 0
2025-12-04 14:19:27,852 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) ixrss: 0
2025-12-04 14:19:27,852 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) majflt: 0
2025-12-04 14:19:27,852 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) maxrss: 546756
2025-12-04 14:19:27,852 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) minflt: 16263
2025-12-04 14:19:27,853 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) msgrcv: 0
2025-12-04 14:19:27,853 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) msgsnd: 0
2025-12-04 14:19:27,853 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) nivcsw: 109
2025-12-04 14:19:27,853 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) nsignals: 0
2025-12-04 14:19:27,853 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) nswap: 0
2025-12-04 14:19:27,853 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) nvcsw: 19
2025-12-04 14:19:27,854 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) oublock: 8
2025-12-04 14:19:27,854 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) stime: 0.248688
2025-12-04 14:19:27,854 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) utime: 0.12181099999999999
2025-12-04 14:19:27,854 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) wtime: 0.539
2025-12-04 14:19:27,884 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

     
Build info:
    Compiler: /home/runner/.ya/tools/v4/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:19:27,884 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2984457) errors:

2025-12-04 14:19:27,885 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 1 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:19:29,827 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:19:29,828 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1915', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 8741\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-04 14:19:29,829 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.24
2025-12-04 14:19:29,834 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.24
2025-12-04 14:19:29,863 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1915', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 8741\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 14:19:29,872 - DEBUG - ya.test.2836152  - execute: Command pid: 2985616
2025-12-04 14:19:30,654 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) rc: 0
2025-12-04 14:19:30,655 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) elapsed time (sec): 0.7812361717224121
2025-12-04 14:19:30,655 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) idrss: 0
2025-12-04 14:19:30,656 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) inblock: 0
2025-12-04 14:19:30,657 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) isrss: 0
2025-12-04 14:19:30,677 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) ixrss: 0
2025-12-04 14:19:30,677 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) majflt: 0
2025-12-04 14:19:30,680 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) maxrss: 555280
2025-12-04 14:19:30,680 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) minflt: 18399
2025-12-04 14:19:30,711 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) msgrcv: 0
2025-12-04 14:19:30,711 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) msgsnd: 0
2025-12-04 14:19:30,711 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) nivcsw: 205
2025-12-04 14:19:30,711 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) nsignals: 0
2025-12-04 14:19:30,711 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) nswap: 0
2025-12-04 14:19:30,711 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) nvcsw: 135
2025-12-04 14:19:30,712 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) oublock: 8
2025-12-04 14:19:30,712 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) stime: 0.326193
2025-12-04 14:19:30,712 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) utime: 0.13203199999999998
2025-12-04 14:19:30,712 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) wtime: 0.786
2025-12-04 14:19:30,728 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-04 14:19:30,728 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2985616) errors:

2025-12-04 14:19:30,743 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:19:30,745 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1915', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-04 14:19:30,840 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.25
2025-12-04 14:19:30,849 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.25
2025-12-04 14:19:30,863 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1915', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 14:19:30,865 - DEBUG - ya.test.2836152  - execute: Command pid: 2986081
2025-12-04 14:19:31,562 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) rc: 0
2025-12-04 14:19:31,563 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) elapsed time (sec): 0.6927809715270996
2025-12-04 14:19:31,563 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) idrss: 0
2025-12-04 14:19:31,563 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) inblock: 0
2025-12-04 14:19:31,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) isrss: 0
2025-12-04 14:19:31,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) ixrss: 0
2025-12-04 14:19:31,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) majflt: 0
2025-12-04 14:19:31,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) maxrss: 555260
2025-12-04 14:19:31,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) minflt: 18401
2025-12-04 14:19:31,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) msgrcv: 0
2025-12-04 14:19:31,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) msgsnd: 0
2025-12-04 14:19:31,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) nivcsw: 278
2025-12-04 14:19:31,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) nsignals: 0
2025-12-04 14:19:31,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) nswap: 0
2025-12-04 14:19:31,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) nvcsw: 108
2025-12-04 14:19:31,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) oublock: 8
2025-12-04 14:19:31,593 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) stime: 0.294605
2025-12-04 14:19:31,593 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) utime: 0.162012
2025-12-04 14:19:31,593 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) wtime: 0.695
2025-12-04 14:19:31,593 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-04 14:19:31,593 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2986081) errors:

2025-12-04 14:19:31,594 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-04 14:19:31,659 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __run: Cluster started and initialized
2025-12-04 14:19:31,747 - INFO - ya.test.2836152  - pytest_runtest_call: Test call (class_name: test_tenants.py::TestTenants, test_name: test_when_deactivate_fat_tenant_creation_another_tenant_is_ok[enable_alter_database_create_hive_first--false])
2025-12-04 14:19:31,748 - INFO - __tests__.test_tenants.2836152  - test_when_deactivate_fat_tenant_creation_another_tenant_is_ok: create fat tenant
2025-12-04 14:19:31,781 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/database_0
2025-12-04 14:19:31,811 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_0: send console operation, token None
2025-12-04 14:19:31,820 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_0: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/database_0\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/database_0\' doesn\'t exist"
}

2025-12-04 14:19:31,912 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_0: wait console operation done
2025-12-04 14:19:32,459 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_0: console operation done
2025-12-04 14:19:32,479 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_0: wait tenant gone
2025-12-04 14:19:32,483 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_0: tenant gone
2025-12-04 14:19:32,503 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_0: create path and declare internals
2025-12-04 14:19:33,033 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_0: start nodes and construct internals
2025-12-04 14:19:33,035 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:19:33,036 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9974
2025-12-04 14:19:33,036 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16566
2025-12-04 14:19:33,041 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7662
2025-12-04 14:19:33,045 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13220
2025-12-04 14:19:33,062 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25763
2025-12-04 14:19:33,071 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:19:33,083 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1915 --tenant=/Root/database_0 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_1/logfile_35e9njlc.log --grpc-port=9974 --mon-port=16566 --ic-port=7662
2025-12-04 14:19:33,083 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:19:33,084 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1915 --tenant=/Root/database_0 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_1/logfile_35e9njlc.log --grpc-port=9974 --mon-port=16566 --ic-port=7662
2025-12-04 14:19:33,110 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:1915', '--tenant=/Root/database_0', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_1/logfile_35e9njlc.log', '--grpc-port=9974', '--mon-port=16566', '--ic-port=7662')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_1' (no wait)
2025-12-04 14:19:33,125 - DEBUG - ya.test.2836152  - execute: Command pid: 2987290
2025-12-04 14:19:33,131 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:9974/1
2025-12-04 14:19:33,160 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.26
2025-12-04 14:19:33,161 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.26
2025-12-04 14:19:33,162 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:19:33,167 - DEBUG - ya.test.2836152  - execute: Command pid: 2987331
2025-12-04 14:19:34,242 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) rc: 0
2025-12-04 14:19:34,242 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) elapsed time (sec): 1.068490982055664
2025-12-04 14:19:34,243 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) idrss: 0
2025-12-04 14:19:34,243 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) inblock: 0
2025-12-04 14:19:34,243 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) isrss: 0
2025-12-04 14:19:34,243 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) ixrss: 0
2025-12-04 14:19:34,243 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) majflt: 0
2025-12-04 14:19:34,253 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) maxrss: 546776
2025-12-04 14:19:34,254 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) minflt: 16257
2025-12-04 14:19:34,254 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) msgrcv: 0
2025-12-04 14:19:34,254 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) msgsnd: 0
2025-12-04 14:19:34,254 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) nivcsw: 184
2025-12-04 14:19:34,254 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) nsignals: 0
2025-12-04 14:19:34,254 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) nswap: 0
2025-12-04 14:19:34,255 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) nvcsw: 18
2025-12-04 14:19:34,268 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) oublock: 8
2025-12-04 14:19:34,275 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) stime: 0.307158
2025-12-04 14:19:34,276 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) utime: 0.14531
2025-12-04 14:19:34,282 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) wtime: 1.074
2025-12-04 14:19:34,286 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

     
Build info:
    Compiler: /home/runner/.ya/tools/v4/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:19:34,292 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2987331) errors:

2025-12-04 14:19:34,297 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 1 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:19:34,301 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_0: wait construction done
2025-12-04 14:19:36,331 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_0: database up
2025-12-04 14:19:36,332 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:19:36,334 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:36,336 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:19:36,336 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:19:36,366 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe704e4b080 state=pending>
2025-12-04 14:19:36,374 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:19:36,401 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:19:36,401 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:19:36,372 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/database_0
2025-12-04 14:19:36,433 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:19:36,438 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 75b588ff-55e2-458b-8e7b-2c0500606955, localhost:1915): creating call state
2025-12-04 14:19:36,480 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 75b588ff-55e2-458b-8e7b-2c0500606955, localhost:1915): request = { database: "/Root/database_0" }
2025-12-04 14:19:36,490 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 75b588ff-55e2-458b-8e7b-2c0500606955, localhost:1915): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 9974 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-12-04 14:19:36,516 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/database_0: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:9974, location , ssl: False>]>
2025-12-04 14:19:36,518 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:19:36,543 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:19:36,543 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:19:36,580 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, 06d62c8f-ecbe-4376-acd7-bde72ffd08ed, ghrun-e3snp7fbzi.auto.internal:9974): creating call state
2025-12-04 14:19:36,595 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, 06d62c8f-ecbe-4376-acd7-bde72ffd08ed, ghrun-e3snp7fbzi.auto.internal:9974): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:36,605 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 06d62c8f-ecbe-4376-acd7-bde72ffd08ed, ghrun-e3snp7fbzi.auto.internal:9974): on response callback started
2025-12-04 14:19:36,643 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, 06d62c8f-ecbe-4376-acd7-bde72ffd08ed, ghrun-e3snp7fbzi.auto.internal:9974): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NmNiYTY5ZTItN2ExZDE2YjYtOGI3YzllY2MtZjc2NGVhMzY=" } } } }
2025-12-04 14:19:36,643 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:19:36,644 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:19:36,645 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 06d62c8f-ecbe-4376-acd7-bde72ffd08ed, ghrun-e3snp7fbzi.auto.internal:9974): on response callback success
2025-12-04 14:19:36,647 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateTable, c37a11a9-8844-4ce4-9978-3e87dfbca5a0, ghrun-e3snp7fbzi.auto.internal:9974): creating call state
2025-12-04 14:19:36,649 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateTable, c37a11a9-8844-4ce4-9978-3e87dfbca5a0, ghrun-e3snp7fbzi.auto.internal:9974): request = { session_id: "ydb://session/3?node_id=50000&id=NmNiYTY5ZTItN2ExZDE2YjYtOGI3YzllY2MtZjc2NGVhMzY=" path: "/Root/database_0/table_0" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" profile { partitioning_policy { uniform_partitions: 10 } } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:19:36,830 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateTable, c37a11a9-8844-4ce4-9978-3e87dfbca5a0, ghrun-e3snp7fbzi.auto.internal:9974): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:19:36,830 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:19:36,837 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DescribeTable, c536878f-5fb3-4f1a-ab3e-18a0d1dd9e48, ghrun-e3snp7fbzi.auto.internal:9974): creating call state
2025-12-04 14:19:36,838 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DescribeTable, c536878f-5fb3-4f1a-ab3e-18a0d1dd9e48, ghrun-e3snp7fbzi.auto.internal:9974): request = { session_id: "ydb://session/3?node_id=50000&id=NmNiYTY5ZTItN2ExZDE2YjYtOGI3YzllY2MtZjc2NGVhMzY=" path: "/Root/database_0/table_0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:19:36,896 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(DescribeTable, c536878f-5fb3-4f1a-ab3e-18a0d1dd9e48, ghrun-e3snp7fbzi.auto.internal:9974): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table_0" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1764857976830 tx_id: 281474976720659 } } columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 10 } } } } }
2025-12-04 14:19:36,948 - DEBUG - __tests__.test_tenants.2836152  - describe_table: > describe table: series, <ydb.table.TableSchemeEntry object at 0x7fe7050b6e60>
2025-12-04 14:19:36,948 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:19:36,949 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_0: remove path and dismantle internals
2025-12-04 14:19:36,949 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/database_0
2025-12-04 14:19:36,949 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_0: send console operation, token None
2025-12-04 14:19:36,961 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_0: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/database_0&cmstid=72057594037936131&txid=1764857976951224&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:19:36,962 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_0: wait console operation done
2025-12-04 14:19:37,471 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_0: console operation done
2025-12-04 14:19:37,475 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_0: wait tenant gone
2025-12-04 14:19:37,481 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_0: tenant gone
2025-12-04 14:19:37,481 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_0: stop nodes
2025-12-04 14:19:54,483 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:9974/1
2025-12-04 14:19:54,503 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_0: database down
2025-12-04 14:19:54,504 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/database_1
2025-12-04 14:19:54,505 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_1: send console operation, token None
2025-12-04 14:19:54,514 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_1: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/database_1\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/database_1\' doesn\'t exist"
}

2025-12-04 14:19:54,519 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_1: wait console operation done
2025-12-04 14:19:55,023 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_1: console operation done
2025-12-04 14:19:55,023 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_1: wait tenant gone
2025-12-04 14:19:55,026 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_1: tenant gone
2025-12-04 14:19:55,027 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_1: create path and declare internals
2025-12-04 14:19:55,558 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_1: start nodes and construct internals
2025-12-04 14:19:55,558 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:19:55,559 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22281
2025-12-04 14:19:55,559 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3394
2025-12-04 14:19:55,560 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29571
2025-12-04 14:19:55,563 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31865
2025-12-04 14:19:55,603 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25134
2025-12-04 14:19:55,647 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:19:55,674 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1915 --tenant=/Root/database_1 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_2/logfile_uoa9h9en.log --grpc-port=22281 --mon-port=3394 --ic-port=29571
2025-12-04 14:19:55,679 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:19:55,683 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1915 --tenant=/Root/database_1 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_2/logfile_uoa9h9en.log --grpc-port=22281 --mon-port=3394 --ic-port=29571
2025-12-04 14:19:55,689 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:1915', '--tenant=/Root/database_1', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_2/logfile_uoa9h9en.log', '--grpc-port=22281', '--mon-port=3394', '--ic-port=29571')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_2' (no wait)
2025-12-04 14:19:55,698 - DEBUG - ya.test.2836152  - execute: Command pid: 2997835
2025-12-04 14:19:55,718 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:22281/2
2025-12-04 14:19:55,722 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.27
2025-12-04 14:19:55,733 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.27
2025-12-04 14:19:55,738 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:19:55,749 - DEBUG - ya.test.2836152  - execute: Command pid: 2997844
2025-12-04 14:19:56,329 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) rc: 0
2025-12-04 14:19:56,329 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) elapsed time (sec): 0.5682530403137207
2025-12-04 14:19:56,329 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) idrss: 0
2025-12-04 14:19:56,330 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) inblock: 0
2025-12-04 14:19:56,330 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) isrss: 0
2025-12-04 14:19:56,347 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) ixrss: 0
2025-12-04 14:19:56,362 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) majflt: 0
2025-12-04 14:19:56,380 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) maxrss: 546756
2025-12-04 14:19:56,393 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) minflt: 16261
2025-12-04 14:19:56,400 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) msgrcv: 0
2025-12-04 14:19:56,410 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) msgsnd: 0
2025-12-04 14:19:56,415 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) nivcsw: 113
2025-12-04 14:19:56,420 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) nsignals: 0
2025-12-04 14:19:56,420 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) nswap: 0
2025-12-04 14:19:56,420 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) nvcsw: 13
2025-12-04 14:19:56,428 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) oublock: 8
2025-12-04 14:19:56,430 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) stime: 0.231706
2025-12-04 14:19:56,442 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) utime: 0.133383
2025-12-04 14:19:56,471 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) wtime: 0.583
2025-12-04 14:19:56,472 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

     
Build info:
    Compiler: /home/runner/.ya/tools/v4/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:19:56,487 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2997844) errors:

2025-12-04 14:19:56,487 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 2 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:19:56,488 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_1: wait construction done
2025-12-04 14:19:58,516 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_1: database up
2025-12-04 14:19:58,517 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:19:58,523 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:58,524 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:19:58,530 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:19:58,583 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe704eb47e0 state=pending>
2025-12-04 14:19:58,562 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/database_1
2025-12-04 14:19:58,584 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:19:58,600 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:19:58,600 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:19:58,599 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 4cb7913b-23d7-4e7d-80fb-0f7aa93135db, localhost:1915): creating call state
2025-12-04 14:19:58,600 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:19:58,607 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 4cb7913b-23d7-4e7d-80fb-0f7aa93135db, localhost:1915): request = { database: "/Root/database_1" }
2025-12-04 14:19:58,652 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 4cb7913b-23d7-4e7d-80fb-0f7aa93135db, localhost:1915): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 22281 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50001 } self_location: "1" } } } }
2025-12-04 14:19:58,653 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/database_1: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:22281, location , ssl: False>]>
2025-12-04 14:19:58,653 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:19:58,667 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:19:58,667 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:19:58,686 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, b5040444-58fb-4f84-95e2-6603a8a627cf, ghrun-e3snp7fbzi.auto.internal:22281): creating call state
2025-12-04 14:19:58,706 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, b5040444-58fb-4f84-95e2-6603a8a627cf, ghrun-e3snp7fbzi.auto.internal:22281): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:58,729 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, b5040444-58fb-4f84-95e2-6603a8a627cf, ghrun-e3snp7fbzi.auto.internal:22281): on response callback started
2025-12-04 14:19:58,751 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, b5040444-58fb-4f84-95e2-6603a8a627cf, ghrun-e3snp7fbzi.auto.internal:22281): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=OWRlNGE4NzktOTFmY2FmMDYtMmQxZmNiMjYtMjAwOWE4MGY=" } } } }
2025-12-04 14:19:58,780 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:19:58,782 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:19:58,792 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, b5040444-58fb-4f84-95e2-6603a8a627cf, ghrun-e3snp7fbzi.auto.internal:22281): on response callback success
2025-12-04 14:19:58,783 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateTable, 1806c06b-d82f-4fb6-84d8-db49d503d849, ghrun-e3snp7fbzi.auto.internal:22281): creating call state
2025-12-04 14:19:58,798 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateTable, 1806c06b-d82f-4fb6-84d8-db49d503d849, ghrun-e3snp7fbzi.auto.internal:22281): request = { session_id: "ydb://session/3?node_id=50001&id=OWRlNGE4NzktOTFmY2FmMDYtMmQxZmNiMjYtMjAwOWE4MGY=" path: "/Root/database_1/table_0" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" profile { partitioning_policy { uniform_partitions: 10 } } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:19:58,962 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateTable, 1806c06b-d82f-4fb6-84d8-db49d503d849, ghrun-e3snp7fbzi.auto.internal:22281): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:19:58,963 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:19:58,964 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DescribeTable, 1d491119-8710-430e-9261-93b14c5d13be, ghrun-e3snp7fbzi.auto.internal:22281): creating call state
2025-12-04 14:19:58,971 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DescribeTable, 1d491119-8710-430e-9261-93b14c5d13be, ghrun-e3snp7fbzi.auto.internal:22281): request = { session_id: "ydb://session/3?node_id=50001&id=OWRlNGE4NzktOTFmY2FmMDYtMmQxZmNiMjYtMjAwOWE4MGY=" path: "/Root/database_1/table_0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:19:59,012 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(DescribeTable, 1d491119-8710-430e-9261-93b14c5d13be, ghrun-e3snp7fbzi.auto.internal:22281): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table_0" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1764857998960 tx_id: 281474976730659 } } columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 10 } } } } }
2025-12-04 14:19:59,013 - DEBUG - __tests__.test_tenants.2836152  - describe_table: > describe table: series, <ydb.table.TableSchemeEntry object at 0x7fe70502d060>
2025-12-04 14:19:59,019 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:19:59,034 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_1: remove path and dismantle internals
2025-12-04 14:19:59,034 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/database_1
2025-12-04 14:19:59,043 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_1: send console operation, token None
2025-12-04 14:19:59,052 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_1: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/database_1&cmstid=72057594037936131&txid=1764857999046509&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:19:59,059 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_1: wait console operation done
2025-12-04 14:19:59,581 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_1: console operation done
2025-12-04 14:19:59,585 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_1: wait tenant gone
2025-12-04 14:19:59,592 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_1: tenant gone
2025-12-04 14:19:59,599 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_1: stop nodes
2025-12-04 14:20:21,603 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:22281/2
2025-12-04 14:20:21,603 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_1: database down
2025-12-04 14:20:21,603 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/database_2
2025-12-04 14:20:21,608 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_2: send console operation, token None
2025-12-04 14:20:21,616 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_2: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/database_2\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/database_2\' doesn\'t exist"
}

2025-12-04 14:20:21,626 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_2: wait console operation done
2025-12-04 14:20:22,132 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_2: console operation done
2025-12-04 14:20:22,132 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_2: wait tenant gone
2025-12-04 14:20:22,140 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_2: tenant gone
2025-12-04 14:20:22,140 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_2: create path and declare internals
2025-12-04 14:20:22,663 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_2: start nodes and construct internals
2025-12-04 14:20:22,663 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:20:22,664 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1705
2025-12-04 14:20:22,670 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/65508
2025-12-04 14:20:22,671 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19575
2025-12-04 14:20:22,672 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21455
2025-12-04 14:20:22,673 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9938
2025-12-04 14:20:22,674 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:20:22,675 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1915 --tenant=/Root/database_2 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_3/logfile_9ybr56xp.log --grpc-port=1705 --mon-port=65508 --ic-port=19575
2025-12-04 14:20:22,675 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-04 14:20:22,675 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1915 --tenant=/Root/database_2 --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_3/logfile_9ybr56xp.log --grpc-port=1705 --mon-port=65508 --ic-port=19575
2025-12-04 14:20:22,677 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:1915', '--tenant=/Root/database_2', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_3/logfile_9ybr56xp.log', '--grpc-port=1705', '--mon-port=65508', '--ic-port=19575')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--false/cluster/slot_3' (no wait)
2025-12-04 14:20:22,687 - DEBUG - ya.test.2836152  - execute: Command pid: 3014101
2025-12-04 14:20:22,688 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:1705/3
2025-12-04 14:20:22,690 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.28
2025-12-04 14:20:22,691 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.28
2025-12-04 14:20:22,692 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:20:22,699 - DEBUG - ya.test.2836152  - execute: Command pid: 3014142
2025-12-04 14:20:23,271 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) rc: 0
2025-12-04 14:20:23,271 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) elapsed time (sec): 0.5701537132263184
2025-12-04 14:20:23,275 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) idrss: 0
2025-12-04 14:20:23,276 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) inblock: 0
2025-12-04 14:20:23,276 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) isrss: 0
2025-12-04 14:20:23,276 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) ixrss: 0
2025-12-04 14:20:23,276 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) majflt: 0
2025-12-04 14:20:23,276 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) maxrss: 546756
2025-12-04 14:20:23,276 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) minflt: 16259
2025-12-04 14:20:23,277 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) msgrcv: 0
2025-12-04 14:20:23,277 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) msgsnd: 0
2025-12-04 14:20:23,277 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) nivcsw: 95
2025-12-04 14:20:23,277 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) nsignals: 0
2025-12-04 14:20:23,277 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) nswap: 0
2025-12-04 14:20:23,277 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) nvcsw: 18
2025-12-04 14:20:23,278 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) oublock: 8
2025-12-04 14:20:23,278 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) stime: 0.239702
2025-12-04 14:20:23,278 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) utime: 0.13316799999999998
2025-12-04 14:20:23,278 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) wtime: 0.577
2025-12-04 14:20:23,279 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

     
Build info:
    Compiler: /home/runner/.ya/tools/v4/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:20:23,279 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 3014142) errors:

2025-12-04 14:20:23,279 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 3 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:20:23,280 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_2: wait construction done
2025-12-04 14:20:25,295 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/database_2: database up
2025-12-04 14:20:25,301 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:20:25,307 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:20:25,308 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:20:25,327 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:20:25,347 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe704e0bbc0 state=pending>
2025-12-04 14:20:25,352 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:20:25,353 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:25,356 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:25,356 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:20:25,336 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/database_2
2025-12-04 14:20:25,361 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, dda49e03-0a3d-4eba-9cfb-7e8d9bd7855e, localhost:1915): creating call state
2025-12-04 14:20:25,362 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, dda49e03-0a3d-4eba-9cfb-7e8d9bd7855e, localhost:1915): request = { database: "/Root/database_2" }
2025-12-04 14:20:25,374 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, dda49e03-0a3d-4eba-9cfb-7e8d9bd7855e, localhost:1915): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 1705 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50002 } self_location: "1" } } } }
2025-12-04 14:20:25,388 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/database_2: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:1705, location , ssl: False>]>
2025-12-04 14:20:25,388 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:20:25,413 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:20:25,414 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:25,419 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, 3ab3acc2-74e7-453c-a659-5d790372a3fb, ghrun-e3snp7fbzi.auto.internal:1705): creating call state
2025-12-04 14:20:25,427 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, 3ab3acc2-74e7-453c-a659-5d790372a3fb, ghrun-e3snp7fbzi.auto.internal:1705): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:20:25,438 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 3ab3acc2-74e7-453c-a659-5d790372a3fb, ghrun-e3snp7fbzi.auto.internal:1705): on response callback started
2025-12-04 14:20:25,443 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, 3ab3acc2-74e7-453c-a659-5d790372a3fb, ghrun-e3snp7fbzi.auto.internal:1705): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=Y2QwYWQ5MWUtNTBjZGQyYWQtYTA1YTViNzUtY2I1YmU4Mjc=" } } } }
2025-12-04 14:20:25,444 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 3ab3acc2-74e7-453c-a659-5d790372a3fb, ghrun-e3snp7fbzi.auto.internal:1705): on response callback success
2025-12-04 14:20:25,444 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:25,444 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:25,445 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateTable, bb8e1ad6-38fc-47ab-b02e-71d081427126, ghrun-e3snp7fbzi.auto.internal:1705): creating call state
2025-12-04 14:20:25,445 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateTable, bb8e1ad6-38fc-47ab-b02e-71d081427126, ghrun-e3snp7fbzi.auto.internal:1705): request = { session_id: "ydb://session/3?node_id=50002&id=Y2QwYWQ5MWUtNTBjZGQyYWQtYTA1YTViNzUtY2I1YmU4Mjc=" path: "/Root/database_2/table_0" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" profile { partitioning_policy { uniform_partitions: 10 } } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:20:25,635 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateTable, bb8e1ad6-38fc-47ab-b02e-71d081427126, ghrun-e3snp7fbzi.auto.internal:1705): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:20:25,638 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:25,639 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DescribeTable, 28173976-d933-4721-8831-ea096fa9211d, ghrun-e3snp7fbzi.auto.internal:1705): creating call state
2025-12-04 14:20:25,647 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DescribeTable, 28173976-d933-4721-8831-ea096fa9211d, ghrun-e3snp7fbzi.auto.internal:1705): request = { session_id: "ydb://session/3?node_id=50002&id=Y2QwYWQ5MWUtNTBjZGQyYWQtYTA1YTViNzUtY2I1YmU4Mjc=" path: "/Root/database_2/table_0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:20:25,720 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(DescribeTable, 28173976-d933-4721-8831-ea096fa9211d, ghrun-e3snp7fbzi.auto.internal:1705): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table_0" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1764858025660 tx_id: 281474976740659 } } columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 10 } } } } }
2025-12-04 14:20:25,721 - DEBUG - __tests__.test_tenants.2836152  - describe_table: > describe table: series, <ydb.table.TableSchemeEntry object at 0x7fe706c52fe0>
2025-12-04 14:20:25,722 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:25,722 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_2: remove path and dismantle internals
2025-12-04 14:20:25,722 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/database_2
2025-12-04 14:20:25,725 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_2: send console operation, token None
2025-12-04 14:20:25,733 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/database_2: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/database_2&cmstid=72057594037936131&txid=1764858025728881&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:20:25,735 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_2: wait console operation done
2025-12-04 14:20:26,250 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/database_2: console operation done
2025-12-04 14:20:26,250 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_2: wait tenant gone
2025-12-04 14:20:26,254 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/database_2: tenant gone
2025-12-04 14:20:26,259 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_2: stop nodes
2025-12-04 14:20:48,267 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:1705/3
2025-12-04 14:20:48,267 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/database_2: database down
2025-12-04 14:20:48,269 - INFO - ya.test.2836152  - pytest_runtest_teardown: Test teardown
2025-12-04 14:20:48,270 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:20:48,270 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:20:48,271 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:20:48,271 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe704e0b170>, reason: session-pool-terminated
2025-12-04 14:20:48,271 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe704e0b170> is no longer active. Current active count 0.
2025-12-04 14:20:48,270 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:20:48,271 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, 38d882b0-86f2-40d1-9046-390105dc05f9, ghrun-e3snp7fbzi.auto.internal:1705): creating call state
2025-12-04 14:20:48,273 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, 38d882b0-86f2-40d1-9046-390105dc05f9, ghrun-e3snp7fbzi.auto.internal:1705): request = { session_id: "ydb://session/3?node_id=50002&id=Y2QwYWQ5MWUtNTBjZGQyYWQtYTA1YTViNzUtY2I1YmU4Mjc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:20:48,274 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe704e0b170>
2025-12-04 14:20:48,275 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:20:48,275 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:20:48,275 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:20:48,275 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:20:48,275 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe704eb4420>, reason: session-pool-terminated
2025-12-04 14:20:48,276 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe704eb4420> is no longer active. Current active count 0.
2025-12-04 14:20:48,277 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, 33794a6e-cc60-4eb6-95b8-ed790d940065, ghrun-e3snp7fbzi.auto.internal:22281): creating call state
2025-12-04 14:20:48,278 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, 33794a6e-cc60-4eb6-95b8-ed790d940065, ghrun-e3snp7fbzi.auto.internal:22281): request = { session_id: "ydb://session/3?node_id=50001&id=OWRlNGE4NzktOTFmY2FmMDYtMmQxZmNiMjYtMjAwOWE4MGY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:20:48,278 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, 38d882b0-86f2-40d1-9046-390105dc05f9, ghrun-e3snp7fbzi.auto.internal:1705): on response callback started
2025-12-04 14:20:48,280 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 38d882b0-86f2-40d1-9046-390105dc05f9, ghrun-e3snp7fbzi.auto.internal:1705): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:1705: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:1705: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T14:20:48.277878217+00:00", grpc_status:14}"
>
2025-12-04 14:20:48,280 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 38d882b0-86f2-40d1-9046-390105dc05f9, ghrun-e3snp7fbzi.auto.internal:1705): unhandled rpc error, disconnecting channel
2025-12-04 14:20:48,280 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:1705
2025-12-04 14:20:48,281 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:20:48,281 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:20:48,278 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:20:48,287 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, 33794a6e-cc60-4eb6-95b8-ed790d940065, ghrun-e3snp7fbzi.auto.internal:22281): on response callback started
2025-12-04 14:20:48,291 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 33794a6e-cc60-4eb6-95b8-ed790d940065, ghrun-e3snp7fbzi.auto.internal:22281): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:22281: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:22281: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T14:20:48.279736857+00:00", grpc_status:14}"
>
2025-12-04 14:20:48,292 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 33794a6e-cc60-4eb6-95b8-ed790d940065, ghrun-e3snp7fbzi.auto.internal:22281): unhandled rpc error, disconnecting channel
2025-12-04 14:20:48,293 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:22281
2025-12-04 14:20:48,294 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe704eb4420>
2025-12-04 14:20:48,294 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:20:48,294 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:20:48,294 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:20:48,298 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:20:48,299 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:20:48,305 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:20:48,306 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:20:48,307 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe704e1bf30>, reason: session-pool-terminated
2025-12-04 14:20:48,310 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe704e1bf30> is no longer active. Current active count 0.
2025-12-04 14:20:48,315 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, 193b9ee0-af82-48a5-8952-79e3de0d2380, ghrun-e3snp7fbzi.auto.internal:9974): creating call state
2025-12-04 14:20:48,315 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, 193b9ee0-af82-48a5-8952-79e3de0d2380, ghrun-e3snp7fbzi.auto.internal:9974): request = { session_id: "ydb://session/3?node_id=50000&id=NmNiYTY5ZTItN2ExZDE2YjYtOGI3YzllY2MtZjc2NGVhMzY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:20:48,316 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe704e1bf30>
2025-12-04 14:20:48,317 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, 193b9ee0-af82-48a5-8952-79e3de0d2380, ghrun-e3snp7fbzi.auto.internal:9974): on response callback started
2025-12-04 14:20:48,319 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:20:48,323 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:20:48,324 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:20:48,324 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:20:48,323 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 193b9ee0-af82-48a5-8952-79e3de0d2380, ghrun-e3snp7fbzi.auto.internal:9974): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:9974: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:9974: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T14:20:48.316328573+00:00", grpc_status:14}"
>
2025-12-04 14:20:48,324 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 193b9ee0-af82-48a5-8952-79e3de0d2380, ghrun-e3snp7fbzi.auto.internal:9974): unhandled rpc error, disconnecting channel
2025-12-04 14:20:48,325 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:9974
2025-12-04 14:20:48,325 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:20:48,326 - DEBUG - ydb.connection.2836152  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:20:48,336 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/database_1
2025-12-04 14:20:48,337 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 10bf89d0-2c2d-43f8-81d4-2f615cbd690a, localhost:1915): creating call state
2025-12-04 14:20:48,337 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 10bf89d0-2c2d-43f8-81d4-2f615cbd690a, localhost:1915): request = { database: "/Root/database_1" }
2025-12-04 14:20:48,338 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/database_2
2025-12-04 14:20:48,339 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 2d3c72a9-ef08-4665-8e01-7f93248a297a, localhost:1915): creating call state
2025-12-04 14:20:48,339 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/database_0
2025-12-04 14:20:48,340 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 2d3c72a9-ef08-4665-8e01-7f93248a297a, localhost:1915): request = { database: "/Root/database_2" }
2025-12-04 14:20:48,340 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, c8005021-eb92-4bca-afa5-565ce32bd27b, localhost:1915): creating call state
2025-12-04 14:20:48,342 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, c8005021-eb92-4bca-afa5-565ce32bd27b, localhost:1915): request = { database: "/Root/database_0" }
2025-12-04 14:20:48,344 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 2d3c72a9-ef08-4665-8e01-7f93248a297a, localhost:1915): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:20:48,344 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/database_2. Endpoint: "localhost:1915". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:20:48,345 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:20:48,347 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 10bf89d0-2c2d-43f8-81d4-2f615cbd690a, localhost:1915): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:20:48,348 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, c8005021-eb92-4bca-afa5-565ce32bd27b, localhost:1915): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:20:48,348 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/database_1. Endpoint: "localhost:1915". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:20:48,349 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/database_0. Endpoint: "localhost:1915". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:20:48,350 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:20:48,350 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:20:48,539 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
2025-12-04 14:20:48,539 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:20:48,540 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:20:48,540 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:20:48,541 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
2025-12-04 14:20:48,541 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:20:48,542 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:20:48,542 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:1915
2025-12-04 14:20:48,543 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
2025-12-04 14:20:48,544 - INFO - ydb.tests.library.fixtures.2836152  - ydb_cluster: destroy ydb_cluster for __tests__.test_tenants
2025-12-04 14:20:55,551 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:1915/1
2025-12-04 14:20:55,551 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9938
2025-12-04 14:20:55,552 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21455
2025-12-04 14:20:55,553 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19575
2025-12-04 14:20:55,554 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/65508
2025-12-04 14:20:55,555 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1705
2025-12-04 14:20:55,555 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25134
2025-12-04 14:20:55,555 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31865
2025-12-04 14:20:55,556 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29571
2025-12-04 14:20:55,557 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3394
2025-12-04 14:20:55,557 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22281
2025-12-04 14:20:55,557 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25763
2025-12-04 14:20:55,558 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13220
2025-12-04 14:20:55,558 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7662
2025-12-04 14:20:55,559 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16566
2025-12-04 14:20:55,559 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9974
2025-12-04 14:20:55,559 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8948
2025-12-04 14:20:55,559 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29665
2025-12-04 14:20:55,559 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26135
2025-12-04 14:20:55,560 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1915
2025-12-04 14:20:55,560 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8741
