2025-12-09 13:38:38,998 - INFO - ya.test.3104096  - pytest_runtest_setup: ####################################################################################################
2025-12-09 13:38:38,999 - INFO - ya.test.3104096  - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true]
2025-12-09 13:38:38,999 - INFO - ya.test.3104096  - pytest_runtest_setup: ####################################################################################################
2025-12-09 13:38:38,999 - INFO - ya.test.3104096  - pytest_runtest_setup: Test setup
2025-12-09 13:38:39,000 - INFO - ydb.tests.library.fixtures.3104096  - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-12-09 13:38:46,001 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:29535/1
2025-12-09 13:38:46,002 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15076
2025-12-09 13:38:46,002 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2161
2025-12-09 13:38:46,003 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11082
2025-12-09 13:38:46,003 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3977
2025-12-09 13:38:46,003 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1113
2025-12-09 13:38:46,003 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2284
2025-12-09 13:38:46,003 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2167
2025-12-09 13:38:46,004 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21349
2025-12-09 13:38:46,004 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7096
2025-12-09 13:38:46,004 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11588
2025-12-09 13:38:46,004 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26042
2025-12-09 13:38:46,004 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29841
2025-12-09 13:38:46,005 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26379
2025-12-09 13:38:46,005 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23407
2025-12-09 13:38:46,005 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19245
2025-12-09 13:38:46,005 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16619
2025-12-09 13:38:46,005 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25480
2025-12-09 13:38:46,006 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24370
2025-12-09 13:38:46,006 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21294
2025-12-09 13:38:46,006 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10423
2025-12-09 13:38:46,006 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19600
2025-12-09 13:38:46,006 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14879
2025-12-09 13:38:46,006 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2188
2025-12-09 13:38:46,007 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29535
2025-12-09 13:38:46,007 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26596
2025-12-09 13:38:46,060 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:38:46,061 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23671
2025-12-09 13:38:46,062 - INFO - ydb.tests.library.fixtures.3104096  - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-12-09 13:38:46,062 - INFO - ydb.tests.library.fixtures.3104096  - ydb_cluster: setup ydb_cluster as local
2025-12-09 13:38:46,063 - DEBUG - ydb.tests.library.harness.kikimr_runner.3104096  - start: Working directory: /home/runner/.ya/build/build_root/d95e/001f29/r3tmp/kikimr_cluster_fpec8iu2
2025-12-09 13:38:46,100 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:38:46,100 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11395
2025-12-09 13:38:46,101 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1908
2025-12-09 13:38:46,101 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7351
2025-12-09 13:38:46,101 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9582
2025-12-09 13:38:46,103 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:46,103 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/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/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_cdkt_2we.log --grpc-port=11395 --mon-port=1908 --ic-port=23671
2025-12-09 13:38:46,103 - DEBUG - ydb.tests.library.harness.kikimr_runner.3104096  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:11395/1, disk_size 68719476736
2025-12-09 13:38:46,103 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:46,103 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/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/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_cdkt_2we.log --grpc-port=11395 --mon-port=1908 --ic-port=23671
2025-12-09 13:38:46,104 - DEBUG - ya.test.3104096  - execute: Executing '('/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_cdkt_2we.log', '--grpc-port=11395', '--mon-port=1908', '--ic-port=23671')' in '/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-12-09 13:38:46,105 - DEBUG - ya.test.3104096  - execute: Command pid: 3279215
2025-12-09 13:38:46,106 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:11395/1
2025-12-09 13:38:46,107 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.37
2025-12-09 13:38:46,107 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.37
2025-12-09 13:38:46,108 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 13:38:46,108 - DEBUG - ya.test.3104096  - execute: Command pid: 3279216
2025-12-09 13:38:46,461 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) rc: 0
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) elapsed time (sec): 0.35233283042907715
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) idrss: 0
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) inblock: 0
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) isrss: 0
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) ixrss: 0
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) majflt: 0
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) maxrss: 547348
2025-12-09 13:38:46,462 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) minflt: 16265
2025-12-09 13:38:46,463 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) msgrcv: 0
2025-12-09 13:38:46,463 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) msgsnd: 0
2025-12-09 13:38:46,464 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) nivcsw: 21
2025-12-09 13:38:46,464 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) nsignals: 0
2025-12-09 13:38:46,464 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) nswap: 0
2025-12-09 13:38:46,465 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) nvcsw: 13
2025-12-09 13:38:46,465 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) oublock: 8
2025-12-09 13:38:46,465 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) stime: 0.184282
2025-12-09 13:38:46,465 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) utime: 0.11617699999999999
2025-12-09 13:38:46,465 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) wtime: 0.353
2025-12-09 13:38:46,465 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) output:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-09 13:38:46,466 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3279216) errors:

2025-12-09 13:38:46,466 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Node 1 version:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-09 13:38:48,266 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:38:48,266 - DEBUG - ydb.tests.library.harness.kikimr_runner.3104096  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11395', '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: 23671\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-09 13:38:48,267 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.38
2025-12-09 13:38:48,268 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.38
2025-12-09 13:38:48,268 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11395', '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: 23671\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-09 13:38:48,269 - DEBUG - ya.test.3104096  - execute: Command pid: 3281729
2025-12-09 13:38:48,691 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) rc: 0
2025-12-09 13:38:48,691 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) elapsed time (sec): 0.42070508003234863
2025-12-09 13:38:48,691 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) idrss: 0
2025-12-09 13:38:48,692 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) inblock: 0
2025-12-09 13:38:48,692 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) isrss: 0
2025-12-09 13:38:48,692 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) ixrss: 0
2025-12-09 13:38:48,692 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) majflt: 0
2025-12-09 13:38:48,692 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) maxrss: 555852
2025-12-09 13:38:48,692 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) minflt: 18405
2025-12-09 13:38:48,692 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) msgrcv: 0
2025-12-09 13:38:48,693 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) msgsnd: 0
2025-12-09 13:38:48,693 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) nivcsw: 24
2025-12-09 13:38:48,693 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) nsignals: 0
2025-12-09 13:38:48,693 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) nswap: 0
2025-12-09 13:38:48,693 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) nvcsw: 118
2025-12-09 13:38:48,694 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) oublock: 8
2025-12-09 13:38:48,694 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) stime: 0.242924
2025-12-09 13:38:48,694 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) utime: 0.125756
2025-12-09 13:38:48,694 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) wtime: 0.422
2025-12-09 13:38:48,694 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-09 13:38:48,694 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3281729) errors:

2025-12-09 13:38:48,695 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:38:48,695 - DEBUG - ydb.tests.library.harness.kikimr_runner.3104096  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11395', '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-09 13:38:48,697 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.39
2025-12-09 13:38:48,698 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.39
2025-12-09 13:38:48,698 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11395', '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-09 13:38:48,699 - DEBUG - ya.test.3104096  - execute: Command pid: 3282433
2025-12-09 13:38:49,092 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) rc: 0
2025-12-09 13:38:49,092 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) elapsed time (sec): 0.3922603130340576
2025-12-09 13:38:49,093 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) idrss: 0
2025-12-09 13:38:49,094 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) inblock: 0
2025-12-09 13:38:49,094 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) isrss: 0
2025-12-09 13:38:49,094 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) ixrss: 0
2025-12-09 13:38:49,094 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) majflt: 0
2025-12-09 13:38:49,095 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) maxrss: 555852
2025-12-09 13:38:49,096 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) minflt: 18407
2025-12-09 13:38:49,096 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) msgrcv: 0
2025-12-09 13:38:49,096 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) msgsnd: 0
2025-12-09 13:38:49,096 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) nivcsw: 11
2025-12-09 13:38:49,096 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) nsignals: 0
2025-12-09 13:38:49,097 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) nswap: 0
2025-12-09 13:38:49,097 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) nvcsw: 133
2025-12-09 13:38:49,097 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) oublock: 8
2025-12-09 13:38:49,097 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) stime: 0.24634099999999998
2025-12-09 13:38:49,097 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) utime: 0.09933099999999999
2025-12-09 13:38:49,097 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) wtime: 0.393
2025-12-09 13:38:49,098 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-09 13:38:49,098 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3282433) errors:

2025-12-09 13:38:49,098 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-09 13:38:49,113 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __run: Cluster started and initialized
2025-12-09 13:38:49,123 - INFO - ya.test.3104096  - pytest_runtest_call: Test call (class_name: test_dynamic_tenants.py, test_name: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true])
2025-12-09 13:38:49,124 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:38:49,637 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:38:49,637 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29016
2025-12-09 13:38:49,638 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7550
2025-12-09 13:38:49,638 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15091
2025-12-09 13:38:49,638 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2706
2025-12-09 13:38:49,639 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5650
2025-12-09 13:38:49,639 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:49,640 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_jg_62qu0.log --grpc-port=29016 --mon-port=7550 --ic-port=15091
2025-12-09 13:38:49,640 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:49,640 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_jg_62qu0.log --grpc-port=29016 --mon-port=7550 --ic-port=15091
2025-12-09 13:38:49,641 - DEBUG - ya.test.3104096  - execute: Executing '('/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:11395', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_jg_62qu0.log', '--grpc-port=29016', '--mon-port=7550', '--ic-port=15091')' in '/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-12-09 13:38:49,643 - DEBUG - ya.test.3104096  - execute: Command pid: 3283075
2025-12-09 13:38:49,643 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:29016/1
2025-12-09 13:38:49,645 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.40
2025-12-09 13:38:49,646 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.40
2025-12-09 13:38:49,646 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 13:38:49,647 - DEBUG - ya.test.3104096  - execute: Command pid: 3283095
2025-12-09 13:38:50,024 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) rc: 0
2025-12-09 13:38:50,025 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) elapsed time (sec): 0.3763887882232666
2025-12-09 13:38:50,025 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) idrss: 0
2025-12-09 13:38:50,025 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) inblock: 0
2025-12-09 13:38:50,025 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) isrss: 0
2025-12-09 13:38:50,025 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) ixrss: 0
2025-12-09 13:38:50,026 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) majflt: 0
2025-12-09 13:38:50,026 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) maxrss: 547340
2025-12-09 13:38:50,026 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) minflt: 16263
2025-12-09 13:38:50,026 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) msgrcv: 0
2025-12-09 13:38:50,026 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) msgsnd: 0
2025-12-09 13:38:50,026 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) nivcsw: 24
2025-12-09 13:38:50,027 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) nsignals: 0
2025-12-09 13:38:50,027 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) nswap: 0
2025-12-09 13:38:50,027 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) nvcsw: 13
2025-12-09 13:38:50,027 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) oublock: 8
2025-12-09 13:38:50,027 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) stime: 0.195073
2025-12-09 13:38:50,027 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) utime: 0.131383
2025-12-09 13:38:50,027 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) wtime: 0.377
2025-12-09 13:38:50,028 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) output:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-09 13:38:50,028 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3283095) errors:

2025-12-09 13:38:50,028 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Node 1 version:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-09 13:38:50,029 - DEBUG - ydb.connection.3104096  - 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-09 13:38:50,031 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:50,031 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:38:50,032 - DEBUG - ydb.connection.3104096  - 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-09 13:38:50,032 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:38:50,035 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f2404c74600 state=pending>
2025-12-09 13:38:50,036 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-09 13:38:50,037 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:50,037 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:50,038 - ERROR - ydb.table.SessionPool.3104096  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-09 13:38:50,039 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:50,039 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, b2d05475-b41f-401f-9f51-46d64820c58b, localhost:11395): creating call state
2025-12-09 13:38:50,040 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, b2d05475-b41f-401f-9f51-46d64820c58b, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:38:50,042 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, b2d05475-b41f-401f-9f51-46d64820c58b, localhost:11395): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-09 13:38:50,042 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:11395". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-09 13:38:50,042 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:38:51,405 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:51,406 - DEBUG - ydb.connection.3104096  - 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-09 13:38:51,416 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:51,417 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 32fa0c1d-83a5-498c-bdd9-904f8b3b072d, localhost:11395): creating call state
2025-12-09 13:38:51,417 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 32fa0c1d-83a5-498c-bdd9-904f8b3b072d, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:38:51,420 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 32fa0c1d-83a5-498c-bdd9-904f8b3b072d, localhost:11395): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 29016 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-09 13:38:51,420 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kvxh57fnmu.auto.internal:29016, location , ssl: False>]>
2025-12-09 13:38:51,421 - DEBUG - ydb.connection.3104096  - 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-09 13:38:51,428 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:38:51,428 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,429 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, d16b631c-2f4c-420a-9ba6-defd7e992583, ghrun-kvxh57fnmu.auto.internal:29016): creating call state
2025-12-09 13:38:51,430 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, d16b631c-2f4c-420a-9ba6-defd7e992583, ghrun-kvxh57fnmu.auto.internal:29016): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:51,433 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, d16b631c-2f4c-420a-9ba6-defd7e992583, ghrun-kvxh57fnmu.auto.internal:29016): on response callback started
2025-12-09 13:38:51,434 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, d16b631c-2f4c-420a-9ba6-defd7e992583, ghrun-kvxh57fnmu.auto.internal:29016): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZDg1MzljYWEtODAzNDc4N2UtNGNiMTE0ZWYtNjZmMDA4ZjY=" } } } }
2025-12-09 13:38:51,434 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,434 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,434 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, d16b631c-2f4c-420a-9ba6-defd7e992583, ghrun-kvxh57fnmu.auto.internal:29016): on response callback success
2025-12-09 13:38:51,435 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, b67f958b-a53c-412c-b657-1dd501d623c2, ghrun-kvxh57fnmu.auto.internal:29016): creating call state
2025-12-09 13:38:51,436 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, b67f958b-a53c-412c-b657-1dd501d623c2, ghrun-kvxh57fnmu.auto.internal:29016): request = { session_id: "ydb://session/3?node_id=50000&id=ZDg1MzljYWEtODAzNDc4N2UtNGNiMTE0ZWYtNjZmMDA4ZjY=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:51,474 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, b67f958b-a53c-412c-b657-1dd501d623c2, ghrun-kvxh57fnmu.auto.internal:29016): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:51,475 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,475 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:38:51,475 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 89bbbca2-bf08-474b-8947-df8de57d601f, ghrun-kvxh57fnmu.auto.internal:29016): creating call state
2025-12-09 13:38:51,476 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 89bbbca2-bf08-474b-8947-df8de57d601f, ghrun-kvxh57fnmu.auto.internal:29016): request = { session_id: "ydb://session/3?node_id=50000&id=ZDg1MzljYWEtODAzNDc4N2UtNGNiMTE0ZWYtNjZmMDA4ZjY=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:51,502 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 89bbbca2-bf08-474b-8947-df8de57d601f, ghrun-kvxh57fnmu.auto.internal:29016): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:51,503 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,503 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:38:51,504 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 6d37e868-d2f7-4695-972f-58db8d00d5f3, ghrun-kvxh57fnmu.auto.internal:29016): creating call state
2025-12-09 13:38:51,504 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 6d37e868-d2f7-4695-972f-58db8d00d5f3, ghrun-kvxh57fnmu.auto.internal:29016): request = { session_id: "ydb://session/3?node_id=50000&id=ZDg1MzljYWEtODAzNDc4N2UtNGNiMTE0ZWYtNjZmMDA4ZjY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_0\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_0\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:51,667 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 6d37e868-d2f7-4695-972f-58db8d00d5f3, ghrun-kvxh57fnmu.auto.internal:29016): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:38:51,668 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,669 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:38:51,669 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 65536e59-a2be-40d1-8661-6851def7bd1b, ghrun-kvxh57fnmu.auto.internal:29016): creating call state
2025-12-09 13:38:51,670 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 65536e59-a2be-40d1-8661-6851def7bd1b, ghrun-kvxh57fnmu.auto.internal:29016): request = { session_id: "ydb://session/3?node_id=50000&id=ZDg1MzljYWEtODAzNDc4N2UtNGNiMTE0ZWYtNjZmMDA4ZjY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:51,718 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 65536e59-a2be-40d1-8661-6851def7bd1b, ghrun-kvxh57fnmu.auto.internal:29016): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-09 13:38:51,718 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,720 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:38:51,720 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, 4def1911-cd98-4725-98b4-56b2416627cc, ghrun-kvxh57fnmu.auto.internal:29016): creating call state
2025-12-09 13:38:51,722 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, 4def1911-cd98-4725-98b4-56b2416627cc, ghrun-kvxh57fnmu.auto.internal:29016): request = { session_id: "ydb://session/3?node_id=50000&id=ZDg1MzljYWEtODAzNDc4N2UtNGNiMTE0ZWYtNjZmMDA4ZjY=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:51,754 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, 4def1911-cd98-4725-98b4-56b2416627cc, ghrun-kvxh57fnmu.auto.internal:29016): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:51,755 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,755 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:38:51,755 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:38:51,755 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:38:51,755 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2404c77170>, reason: session-pool-terminated
2025-12-09 13:38:51,756 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f2404c77170> is no longer active. Current active count 0.
2025-12-09 13:38:51,756 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, a67ca26e-e6bc-4e7e-aaf2-af7771531c40, ghrun-kvxh57fnmu.auto.internal:29016): creating call state
2025-12-09 13:38:51,756 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, a67ca26e-e6bc-4e7e-aaf2-af7771531c40, ghrun-kvxh57fnmu.auto.internal:29016): request = { session_id: "ydb://session/3?node_id=50000&id=ZDg1MzljYWEtODAzNDc4N2UtNGNiMTE0ZWYtNjZmMDA4ZjY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:51,758 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:38:51,759 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, a67ca26e-e6bc-4e7e-aaf2-af7771531c40, ghrun-kvxh57fnmu.auto.internal:29016): on response callback started
2025-12-09 13:38:51,760 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, a67ca26e-e6bc-4e7e-aaf2-af7771531c40, ghrun-kvxh57fnmu.auto.internal:29016): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:51,760 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, a67ca26e-e6bc-4e7e-aaf2-af7771531c40, ghrun-kvxh57fnmu.auto.internal:29016): on response callback success
2025-12-09 13:38:51,760 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2404c77170>
2025-12-09 13:38:51,760 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:38:51,761 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:38:51,761 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:38:51,761 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:38:51,761 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:29016
2025-12-09 13:38:51,763 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:38:51,763 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:38:51,764 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:38:51,764 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-09 13:38:51,769 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765287531766689&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:38:51,769 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-09 13:38:52,273 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:38:52,274 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-09 13:38:52,277 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:38:53,278 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:29016/1
2025-12-09 13:38:53,278 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 0
2025-12-09 13:38:53,278 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:38:53,789 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:38:53,790 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22660
2025-12-09 13:38:53,790 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21902
2025-12-09 13:38:53,790 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21902
2025-12-09 13:38:53,790 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21903
2025-12-09 13:38:53,791 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30128
2025-12-09 13:38:53,791 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20849
2025-12-09 13:38:53,791 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4794
2025-12-09 13:38:53,791 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:53,792 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_gcmuvq70.log --grpc-port=22660 --mon-port=21903 --ic-port=30128
2025-12-09 13:38:53,792 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:53,792 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_gcmuvq70.log --grpc-port=22660 --mon-port=21903 --ic-port=30128
2025-12-09 13:38:53,793 - DEBUG - ya.test.3104096  - execute: Executing '('/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:11395', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_gcmuvq70.log', '--grpc-port=22660', '--mon-port=21903', '--ic-port=30128')' in '/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-12-09 13:38:53,794 - DEBUG - ya.test.3104096  - execute: Command pid: 3288060
2025-12-09 13:38:53,794 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:22660/2
2025-12-09 13:38:53,795 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.41
2025-12-09 13:38:53,796 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.41
2025-12-09 13:38:53,797 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 13:38:53,797 - DEBUG - ya.test.3104096  - execute: Command pid: 3288064
2025-12-09 13:38:54,202 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) rc: 0
2025-12-09 13:38:54,202 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) elapsed time (sec): 0.4034616947174072
2025-12-09 13:38:54,203 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) idrss: 0
2025-12-09 13:38:54,203 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) inblock: 0
2025-12-09 13:38:54,203 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) isrss: 0
2025-12-09 13:38:54,203 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) ixrss: 0
2025-12-09 13:38:54,205 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) majflt: 0
2025-12-09 13:38:54,206 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) maxrss: 547336
2025-12-09 13:38:54,206 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) minflt: 16266
2025-12-09 13:38:54,206 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) msgrcv: 0
2025-12-09 13:38:54,207 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) msgsnd: 0
2025-12-09 13:38:54,207 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) nivcsw: 20
2025-12-09 13:38:54,208 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) nsignals: 0
2025-12-09 13:38:54,208 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) nswap: 0
2025-12-09 13:38:54,209 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) nvcsw: 12
2025-12-09 13:38:54,209 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) oublock: 8
2025-12-09 13:38:54,209 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) stime: 0.23362
2025-12-09 13:38:54,210 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) utime: 0.098991
2025-12-09 13:38:54,211 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) wtime: 0.404
2025-12-09 13:38:54,211 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) output:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-09 13:38:54,212 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3288064) errors:

2025-12-09 13:38:54,212 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Node 2 version:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-09 13:38:54,213 - DEBUG - ydb.connection.3104096  - 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-09 13:38:54,215 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:54,216 - DEBUG - ydb.connection.3104096  - 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-09 13:38:54,219 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:38:54,219 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:38:54,220 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f2404be8ba0 state=pending>
2025-12-09 13:38:54,220 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-09 13:38:54,220 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:54,220 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:54,221 - ERROR - ydb.table.SessionPool.3104096  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-09 13:38:54,224 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:54,225 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 283aa028-02bd-4377-9df4-2e7308204f9a, localhost:11395): creating call state
2025-12-09 13:38:54,225 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 283aa028-02bd-4377-9df4-2e7308204f9a, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:38:54,227 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 283aa028-02bd-4377-9df4-2e7308204f9a, localhost:11395): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-09 13:38:54,228 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:11395". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-09 13:38:54,229 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:38:56,402 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:56,402 - DEBUG - ydb.connection.3104096  - 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-09 13:38:56,408 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:56,408 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 1ec99bcd-2be6-4a9a-9a75-36d020a0bfa3, localhost:11395): creating call state
2025-12-09 13:38:56,409 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 1ec99bcd-2be6-4a9a-9a75-36d020a0bfa3, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:38:56,411 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 1ec99bcd-2be6-4a9a-9a75-36d020a0bfa3, localhost:11395): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 22660 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-09 13:38:56,412 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kvxh57fnmu.auto.internal:22660, location , ssl: False>]>
2025-12-09 13:38:56,412 - DEBUG - ydb.connection.3104096  - 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-09 13:38:56,419 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:38:56,419 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,420 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, a24b8322-b0ef-4de6-aa67-da5b9a57ddd1, ghrun-kvxh57fnmu.auto.internal:22660): creating call state
2025-12-09 13:38:56,421 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, a24b8322-b0ef-4de6-aa67-da5b9a57ddd1, ghrun-kvxh57fnmu.auto.internal:22660): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:56,423 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, a24b8322-b0ef-4de6-aa67-da5b9a57ddd1, ghrun-kvxh57fnmu.auto.internal:22660): on response callback started
2025-12-09 13:38:56,425 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, a24b8322-b0ef-4de6-aa67-da5b9a57ddd1, ghrun-kvxh57fnmu.auto.internal:22660): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=MWIyYzllMTYtYWFlZTU4YWMtYzAzM2VhNzUtNDFkOTAzYmU=" } } } }
2025-12-09 13:38:56,425 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,426 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,426 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, a24b8322-b0ef-4de6-aa67-da5b9a57ddd1, ghrun-kvxh57fnmu.auto.internal:22660): on response callback success
2025-12-09 13:38:56,427 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 551cd2ac-e661-415f-8636-1747307060c3, ghrun-kvxh57fnmu.auto.internal:22660): creating call state
2025-12-09 13:38:56,428 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 551cd2ac-e661-415f-8636-1747307060c3, ghrun-kvxh57fnmu.auto.internal:22660): request = { session_id: "ydb://session/3?node_id=50001&id=MWIyYzllMTYtYWFlZTU4YWMtYzAzM2VhNzUtNDFkOTAzYmU=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:56,479 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 551cd2ac-e661-415f-8636-1747307060c3, ghrun-kvxh57fnmu.auto.internal:22660): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:56,482 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,482 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:38:56,483 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 56686425-384b-46a9-847f-edf3bc44b96b, ghrun-kvxh57fnmu.auto.internal:22660): creating call state
2025-12-09 13:38:56,487 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 56686425-384b-46a9-847f-edf3bc44b96b, ghrun-kvxh57fnmu.auto.internal:22660): request = { session_id: "ydb://session/3?node_id=50001&id=MWIyYzllMTYtYWFlZTU4YWMtYzAzM2VhNzUtNDFkOTAzYmU=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:56,515 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 56686425-384b-46a9-847f-edf3bc44b96b, ghrun-kvxh57fnmu.auto.internal:22660): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:56,515 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,516 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:38:56,516 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 35674980-a812-4157-b83d-71ba019ec2c1, ghrun-kvxh57fnmu.auto.internal:22660): creating call state
2025-12-09 13:38:56,517 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 35674980-a812-4157-b83d-71ba019ec2c1, ghrun-kvxh57fnmu.auto.internal:22660): request = { session_id: "ydb://session/3?node_id=50001&id=MWIyYzllMTYtYWFlZTU4YWMtYzAzM2VhNzUtNDFkOTAzYmU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_1\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_1\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:56,545 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 35674980-a812-4157-b83d-71ba019ec2c1, ghrun-kvxh57fnmu.auto.internal:22660): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:38:56,545 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,545 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:38:56,546 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, a627a3f4-e27b-4e51-8c30-e9505593c837, ghrun-kvxh57fnmu.auto.internal:22660): creating call state
2025-12-09 13:38:56,546 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, a627a3f4-e27b-4e51-8c30-e9505593c837, ghrun-kvxh57fnmu.auto.internal:22660): request = { session_id: "ydb://session/3?node_id=50001&id=MWIyYzllMTYtYWFlZTU4YWMtYzAzM2VhNzUtNDFkOTAzYmU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:56,588 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, a627a3f4-e27b-4e51-8c30-e9505593c837, ghrun-kvxh57fnmu.auto.internal:22660): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-09 13:38:56,589 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,589 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:38:56,590 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, 0baa1f5a-1253-4d20-a903-339322c18d88, ghrun-kvxh57fnmu.auto.internal:22660): creating call state
2025-12-09 13:38:56,590 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, 0baa1f5a-1253-4d20-a903-339322c18d88, ghrun-kvxh57fnmu.auto.internal:22660): request = { session_id: "ydb://session/3?node_id=50001&id=MWIyYzllMTYtYWFlZTU4YWMtYzAzM2VhNzUtNDFkOTAzYmU=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:56,608 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, 0baa1f5a-1253-4d20-a903-339322c18d88, ghrun-kvxh57fnmu.auto.internal:22660): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:56,609 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,609 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:38:56,615 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:38:56,615 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:38:56,616 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:38:56,616 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2404be8e20>, reason: session-pool-terminated
2025-12-09 13:38:56,616 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f2404be8e20> is no longer active. Current active count 0.
2025-12-09 13:38:56,617 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, a8bdf6d4-dca0-45ff-905f-77839048939b, ghrun-kvxh57fnmu.auto.internal:22660): creating call state
2025-12-09 13:38:56,617 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, a8bdf6d4-dca0-45ff-905f-77839048939b, ghrun-kvxh57fnmu.auto.internal:22660): request = { session_id: "ydb://session/3?node_id=50001&id=MWIyYzllMTYtYWFlZTU4YWMtYzAzM2VhNzUtNDFkOTAzYmU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:56,619 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2404be8e20>
2025-12-09 13:38:56,620 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:38:56,620 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:38:56,620 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:38:56,620 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:38:56,621 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:22660
2025-12-09 13:38:56,621 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:38:56,622 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:38:56,623 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:38:56,623 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-09 13:38:56,626 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, a8bdf6d4-dca0-45ff-905f-77839048939b, ghrun-kvxh57fnmu.auto.internal:22660): on response callback started
2025-12-09 13:38:56,627 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, a8bdf6d4-dca0-45ff-905f-77839048939b, ghrun-kvxh57fnmu.auto.internal:22660): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:56,632 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, a8bdf6d4-dca0-45ff-905f-77839048939b, ghrun-kvxh57fnmu.auto.internal:22660): on response callback success
2025-12-09 13:38:56,629 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765287536626587&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:38:56,638 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-09 13:38:57,141 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:38:57,142 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-09 13:38:57,145 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:38:58,146 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:22660/2
2025-12-09 13:38:58,146 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 1
2025-12-09 13:38:58,146 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:38:58,669 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:38:58,685 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20877
2025-12-09 13:38:58,687 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24666
2025-12-09 13:38:58,687 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63528
2025-12-09 13:38:58,688 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14807
2025-12-09 13:38:58,690 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27564
2025-12-09 13:38:58,692 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:58,693 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_2f3w_pfr.log --grpc-port=20877 --mon-port=24666 --ic-port=63528
2025-12-09 13:38:58,694 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:38:58,696 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_2f3w_pfr.log --grpc-port=20877 --mon-port=24666 --ic-port=63528
2025-12-09 13:38:58,697 - DEBUG - ya.test.3104096  - execute: Executing '('/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:11395', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_2f3w_pfr.log', '--grpc-port=20877', '--mon-port=24666', '--ic-port=63528')' in '/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-12-09 13:38:58,699 - DEBUG - ya.test.3104096  - execute: Command pid: 3292790
2025-12-09 13:38:58,700 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:20877/3
2025-12-09 13:38:58,706 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.42
2025-12-09 13:38:58,708 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.42
2025-12-09 13:38:58,708 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 13:38:58,719 - DEBUG - ya.test.3104096  - execute: Command pid: 3292791
2025-12-09 13:38:59,097 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) rc: 0
2025-12-09 13:38:59,098 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) elapsed time (sec): 0.37787294387817383
2025-12-09 13:38:59,098 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) idrss: 0
2025-12-09 13:38:59,098 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) inblock: 0
2025-12-09 13:38:59,098 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) isrss: 0
2025-12-09 13:38:59,099 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) ixrss: 0
2025-12-09 13:38:59,099 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) majflt: 0
2025-12-09 13:38:59,099 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) maxrss: 547332
2025-12-09 13:38:59,100 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) minflt: 16265
2025-12-09 13:38:59,100 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) msgrcv: 0
2025-12-09 13:38:59,101 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) msgsnd: 0
2025-12-09 13:38:59,101 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) nivcsw: 28
2025-12-09 13:38:59,101 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) nsignals: 0
2025-12-09 13:38:59,102 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) nswap: 0
2025-12-09 13:38:59,102 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) nvcsw: 15
2025-12-09 13:38:59,102 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) oublock: 8
2025-12-09 13:38:59,102 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) stime: 0.222568
2025-12-09 13:38:59,102 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) utime: 0.105214
2025-12-09 13:38:59,102 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) wtime: 0.379
2025-12-09 13:38:59,103 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) output:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-09 13:38:59,103 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3292791) errors:

2025-12-09 13:38:59,103 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Node 3 version:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-09 13:38:59,103 - DEBUG - ydb.connection.3104096  - 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-09 13:38:59,105 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:59,106 - DEBUG - ydb.connection.3104096  - 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-09 13:38:59,107 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:38:59,110 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:38:59,110 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f24052c8470 state=pending>
2025-12-09 13:38:59,110 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-09 13:38:59,110 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:38:59,111 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:38:59,111 - ERROR - ydb.table.SessionPool.3104096  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-09 13:38:59,114 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:59,114 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, ffc44f4f-4a4d-4806-b80c-589826f45e82, localhost:11395): creating call state
2025-12-09 13:38:59,115 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, ffc44f4f-4a4d-4806-b80c-589826f45e82, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:38:59,122 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, ffc44f4f-4a4d-4806-b80c-589826f45e82, localhost:11395): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-09 13:38:59,123 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:11395". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-09 13:38:59,126 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:39:01,013 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:39:01,013 - DEBUG - ydb.connection.3104096  - 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-09 13:39:01,018 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:39:01,018 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, afd9be0b-a229-4131-ae2c-1d8eb421ac76, localhost:11395): creating call state
2025-12-09 13:39:01,019 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, afd9be0b-a229-4131-ae2c-1d8eb421ac76, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:39:01,022 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, afd9be0b-a229-4131-ae2c-1d8eb421ac76, localhost:11395): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 20877 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-09 13:39:01,022 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kvxh57fnmu.auto.internal:20877, location , ssl: False>]>
2025-12-09 13:39:01,023 - DEBUG - ydb.connection.3104096  - 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-09 13:39:01,031 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:39:01,031 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,032 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, ab832292-281d-44c3-9f01-0dd43d262476, ghrun-kvxh57fnmu.auto.internal:20877): creating call state
2025-12-09 13:39:01,034 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, ab832292-281d-44c3-9f01-0dd43d262476, ghrun-kvxh57fnmu.auto.internal:20877): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:39:01,037 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, ab832292-281d-44c3-9f01-0dd43d262476, ghrun-kvxh57fnmu.auto.internal:20877): on response callback started
2025-12-09 13:39:01,038 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, ab832292-281d-44c3-9f01-0dd43d262476, ghrun-kvxh57fnmu.auto.internal:20877): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=Zjk5MTJkZTAtMzcyNDM3YTEtMjM5M2Y3NjctNzY4Zjk3YzE=" } } } }
2025-12-09 13:39:01,038 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,038 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,038 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, ab832292-281d-44c3-9f01-0dd43d262476, ghrun-kvxh57fnmu.auto.internal:20877): on response callback success
2025-12-09 13:39:01,039 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 52f2576d-2dc0-44ff-b28a-ec8970d6adae, ghrun-kvxh57fnmu.auto.internal:20877): creating call state
2025-12-09 13:39:01,040 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 52f2576d-2dc0-44ff-b28a-ec8970d6adae, ghrun-kvxh57fnmu.auto.internal:20877): request = { session_id: "ydb://session/3?node_id=50002&id=Zjk5MTJkZTAtMzcyNDM3YTEtMjM5M2Y3NjctNzY4Zjk3YzE=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:01,088 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 52f2576d-2dc0-44ff-b28a-ec8970d6adae, ghrun-kvxh57fnmu.auto.internal:20877): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:01,088 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,089 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:39:01,090 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 85d33883-8c61-4d4d-9ce1-fe6e7d0724ba, ghrun-kvxh57fnmu.auto.internal:20877): creating call state
2025-12-09 13:39:01,091 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 85d33883-8c61-4d4d-9ce1-fe6e7d0724ba, ghrun-kvxh57fnmu.auto.internal:20877): request = { session_id: "ydb://session/3?node_id=50002&id=Zjk5MTJkZTAtMzcyNDM3YTEtMjM5M2Y3NjctNzY4Zjk3YzE=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:01,115 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 85d33883-8c61-4d4d-9ce1-fe6e7d0724ba, ghrun-kvxh57fnmu.auto.internal:20877): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:01,116 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,116 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:39:01,117 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 5fc945a0-6be6-4ef6-8ccd-1ebc5396e2d4, ghrun-kvxh57fnmu.auto.internal:20877): creating call state
2025-12-09 13:39:01,117 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 5fc945a0-6be6-4ef6-8ccd-1ebc5396e2d4, ghrun-kvxh57fnmu.auto.internal:20877): request = { session_id: "ydb://session/3?node_id=50002&id=Zjk5MTJkZTAtMzcyNDM3YTEtMjM5M2Y3NjctNzY4Zjk3YzE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_2\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_2\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:01,145 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 5fc945a0-6be6-4ef6-8ccd-1ebc5396e2d4, ghrun-kvxh57fnmu.auto.internal:20877): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:39:01,145 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,145 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:39:01,149 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 13e95703-5c34-4072-8afc-bb4f236381a1, ghrun-kvxh57fnmu.auto.internal:20877): creating call state
2025-12-09 13:39:01,150 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 13e95703-5c34-4072-8afc-bb4f236381a1, ghrun-kvxh57fnmu.auto.internal:20877): request = { session_id: "ydb://session/3?node_id=50002&id=Zjk5MTJkZTAtMzcyNDM3YTEtMjM5M2Y3NjctNzY4Zjk3YzE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:01,188 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 13e95703-5c34-4072-8afc-bb4f236381a1, ghrun-kvxh57fnmu.auto.internal:20877): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-09 13:39:01,189 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,189 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:39:01,190 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, 9bffa7df-3103-4aa0-82bb-23ff8b1916a6, ghrun-kvxh57fnmu.auto.internal:20877): creating call state
2025-12-09 13:39:01,190 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, 9bffa7df-3103-4aa0-82bb-23ff8b1916a6, ghrun-kvxh57fnmu.auto.internal:20877): request = { session_id: "ydb://session/3?node_id=50002&id=Zjk5MTJkZTAtMzcyNDM3YTEtMjM5M2Y3NjctNzY4Zjk3YzE=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:01,208 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, 9bffa7df-3103-4aa0-82bb-23ff8b1916a6, ghrun-kvxh57fnmu.auto.internal:20877): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:01,208 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,209 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:39:01,209 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:39:01,209 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:39:01,209 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f24052cb990>, reason: session-pool-terminated
2025-12-09 13:39:01,209 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f24052cb990> is no longer active. Current active count 0.
2025-12-09 13:39:01,210 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, ddeb956c-949e-47e2-a9d3-bace738eda8d, ghrun-kvxh57fnmu.auto.internal:20877): creating call state
2025-12-09 13:39:01,210 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, ddeb956c-949e-47e2-a9d3-bace738eda8d, ghrun-kvxh57fnmu.auto.internal:20877): request = { session_id: "ydb://session/3?node_id=50002&id=Zjk5MTJkZTAtMzcyNDM3YTEtMjM5M2Y3NjctNzY4Zjk3YzE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:39:01,212 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:39:01,212 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, ddeb956c-949e-47e2-a9d3-bace738eda8d, ghrun-kvxh57fnmu.auto.internal:20877): on response callback started
2025-12-09 13:39:01,214 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, ddeb956c-949e-47e2-a9d3-bace738eda8d, ghrun-kvxh57fnmu.auto.internal:20877): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:01,214 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, ddeb956c-949e-47e2-a9d3-bace738eda8d, ghrun-kvxh57fnmu.auto.internal:20877): on response callback success
2025-12-09 13:39:01,215 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f24052cb990>
2025-12-09 13:39:01,215 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:39:01,215 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:39:01,216 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:39:01,216 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:39:01,221 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:20877
2025-12-09 13:39:01,234 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:39:01,235 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:39:01,235 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:39:01,236 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-09 13:39:01,241 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765287541238682&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:39:01,241 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-09 13:39:01,745 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:39:01,745 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-09 13:39:01,748 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:39:02,749 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:20877/3
2025-12-09 13:39:02,749 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 2
2025-12-09 13:39:02,749 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:39:03,265 - DEBUG - ya.test.3104096  - get_binary: Binary was found by /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd
2025-12-09 13:39:03,266 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12330
2025-12-09 13:39:03,266 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22260
2025-12-09 13:39:03,267 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1596
2025-12-09 13:39:03,268 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12042
2025-12-09 13:39:03,272 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30874
2025-12-09 13:39:03,275 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:39:03,275 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_k1prvmdt.log --grpc-port=12330 --mon-port=22260 --ic-port=1596
2025-12-09 13:39:03,275 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-09 13:39:03,276 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __make_run_command: Final command: /home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:11395 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_k1prvmdt.log --grpc-port=12330 --mon-port=22260 --ic-port=1596
2025-12-09 13:39:03,276 - DEBUG - ya.test.3104096  - execute: Executing '('/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:11395', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_k1prvmdt.log', '--grpc-port=12330', '--mon-port=22260', '--ic-port=1596')' in '/home/runner/.ya/build/build_root/d95e/001f29/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4' (no wait)
2025-12-09 13:39:03,279 - DEBUG - ya.test.3104096  - execute: Command pid: 3297299
2025-12-09 13:39:03,280 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:12330/4
2025-12-09 13:39:03,282 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.43
2025-12-09 13:39:03,283 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.43
2025-12-09 13:39:03,283 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 13:39:03,284 - DEBUG - ya.test.3104096  - execute: Command pid: 3297300
2025-12-09 13:39:03,711 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) rc: 0
2025-12-09 13:39:03,712 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) elapsed time (sec): 0.4268207550048828
2025-12-09 13:39:03,712 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) idrss: 0
2025-12-09 13:39:03,712 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) inblock: 0
2025-12-09 13:39:03,712 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) isrss: 0
2025-12-09 13:39:03,712 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) ixrss: 0
2025-12-09 13:39:03,713 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) majflt: 0
2025-12-09 13:39:03,714 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) maxrss: 547328
2025-12-09 13:39:03,714 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) minflt: 16265
2025-12-09 13:39:03,714 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) msgrcv: 0
2025-12-09 13:39:03,714 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) msgsnd: 0
2025-12-09 13:39:03,720 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) nivcsw: 15
2025-12-09 13:39:03,721 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) nsignals: 0
2025-12-09 13:39:03,721 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) nswap: 0
2025-12-09 13:39:03,721 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) nvcsw: 13
2025-12-09 13:39:03,722 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) oublock: 8
2025-12-09 13:39:03,722 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) stime: 0.252362
2025-12-09 13:39:03,722 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) utime: 0.122238
2025-12-09 13:39:03,723 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) wtime: 0.428
2025-12-09 13:39:03,725 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) output:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-09 13:39:03,726 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3297300) errors:

2025-12-09 13:39:03,726 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Node 4 version:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kvxh57fnmu    Host information: 
        Linux ghrun-kvxh57fnmu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-09 13:39:03,727 - DEBUG - ydb.connection.3104096  - 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-09 13:39:03,728 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:39:03,729 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:39:03,729 - DEBUG - ydb.connection.3104096  - 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-09 13:39:03,730 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:39:03,733 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f2404a12d60 state=pending>
2025-12-09 13:39:03,734 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-09 13:39:03,735 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:03,735 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:03,736 - ERROR - ydb.table.SessionPool.3104096  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-09 13:39:03,736 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:39:03,737 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 1395ad8e-d830-4e02-ae98-67559da409d8, localhost:11395): creating call state
2025-12-09 13:39:03,737 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 1395ad8e-d830-4e02-ae98-67559da409d8, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:39:03,739 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 1395ad8e-d830-4e02-ae98-67559da409d8, localhost:11395): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-09 13:39:03,739 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:11395". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-09 13:39:03,740 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:39:05,616 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:39:05,617 - DEBUG - ydb.connection.3104096  - 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-09 13:39:05,627 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:39:05,627 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, b05553d1-d570-4178-988d-80d72caaf817, localhost:11395): creating call state
2025-12-09 13:39:05,627 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, b05553d1-d570-4178-988d-80d72caaf817, localhost:11395): request = { database: "/Root/users/database" }
2025-12-09 13:39:05,630 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, b05553d1-d570-4178-988d-80d72caaf817, localhost:11395): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 12330 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: 50003 } self_location: "1" } } } }
2025-12-09 13:39:05,631 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kvxh57fnmu.auto.internal:12330, location , ssl: False>]>
2025-12-09 13:39:05,631 - DEBUG - ydb.connection.3104096  - 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-09 13:39:05,644 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:39:05,644 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,645 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, 715422c0-d6e3-4e5b-a368-163ddc8f2630, ghrun-kvxh57fnmu.auto.internal:12330): creating call state
2025-12-09 13:39:05,645 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, 715422c0-d6e3-4e5b-a368-163ddc8f2630, ghrun-kvxh57fnmu.auto.internal:12330): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:39:05,651 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 715422c0-d6e3-4e5b-a368-163ddc8f2630, ghrun-kvxh57fnmu.auto.internal:12330): on response callback started
2025-12-09 13:39:05,652 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, 715422c0-d6e3-4e5b-a368-163ddc8f2630, ghrun-kvxh57fnmu.auto.internal:12330): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=MmQyY2QxMjItZjMyZGI4NjMtNWI4M2JhYjktNGVmNmJiNjQ=" } } } }
2025-12-09 13:39:05,652 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,653 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,653 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 715422c0-d6e3-4e5b-a368-163ddc8f2630, ghrun-kvxh57fnmu.auto.internal:12330): on response callback success
2025-12-09 13:39:05,658 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 6dbc91ee-b97a-46c8-9c09-be036f838502, ghrun-kvxh57fnmu.auto.internal:12330): creating call state
2025-12-09 13:39:05,659 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 6dbc91ee-b97a-46c8-9c09-be036f838502, ghrun-kvxh57fnmu.auto.internal:12330): request = { session_id: "ydb://session/3?node_id=50003&id=MmQyY2QxMjItZjMyZGI4NjMtNWI4M2JhYjktNGVmNmJiNjQ=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:05,706 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 6dbc91ee-b97a-46c8-9c09-be036f838502, ghrun-kvxh57fnmu.auto.internal:12330): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:05,707 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,707 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:39:05,708 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 3a1a9a65-4edc-48ff-ac66-6f05ea2e6b8e, ghrun-kvxh57fnmu.auto.internal:12330): creating call state
2025-12-09 13:39:05,708 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 3a1a9a65-4edc-48ff-ac66-6f05ea2e6b8e, ghrun-kvxh57fnmu.auto.internal:12330): request = { session_id: "ydb://session/3?node_id=50003&id=MmQyY2QxMjItZjMyZGI4NjMtNWI4M2JhYjktNGVmNmJiNjQ=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:05,734 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 3a1a9a65-4edc-48ff-ac66-6f05ea2e6b8e, ghrun-kvxh57fnmu.auto.internal:12330): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:05,735 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,735 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:39:05,736 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, c817b456-930f-4490-8c7e-71b9f3977170, ghrun-kvxh57fnmu.auto.internal:12330): creating call state
2025-12-09 13:39:05,736 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, c817b456-930f-4490-8c7e-71b9f3977170, ghrun-kvxh57fnmu.auto.internal:12330): request = { session_id: "ydb://session/3?node_id=50003&id=MmQyY2QxMjItZjMyZGI4NjMtNWI4M2JhYjktNGVmNmJiNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_3\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_3\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:05,760 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, c817b456-930f-4490-8c7e-71b9f3977170, ghrun-kvxh57fnmu.auto.internal:12330): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:39:05,761 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,761 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:39:05,762 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 654a1d23-3a2d-41e5-acbe-2da743da25b3, ghrun-kvxh57fnmu.auto.internal:12330): creating call state
2025-12-09 13:39:05,762 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 654a1d23-3a2d-41e5-acbe-2da743da25b3, ghrun-kvxh57fnmu.auto.internal:12330): request = { session_id: "ydb://session/3?node_id=50003&id=MmQyY2QxMjItZjMyZGI4NjMtNWI4M2JhYjktNGVmNmJiNjQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:05,796 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 654a1d23-3a2d-41e5-acbe-2da743da25b3, ghrun-kvxh57fnmu.auto.internal:12330): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-09 13:39:05,797 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,798 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:39:05,798 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, 80dc212c-c236-494f-8824-dba0f0d4e3e0, ghrun-kvxh57fnmu.auto.internal:12330): creating call state
2025-12-09 13:39:05,799 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, 80dc212c-c236-494f-8824-dba0f0d4e3e0, ghrun-kvxh57fnmu.auto.internal:12330): request = { session_id: "ydb://session/3?node_id=50003&id=MmQyY2QxMjItZjMyZGI4NjMtNWI4M2JhYjktNGVmNmJiNjQ=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:39:05,828 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, 80dc212c-c236-494f-8824-dba0f0d4e3e0, ghrun-kvxh57fnmu.auto.internal:12330): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:05,828 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,828 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:39:05,829 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:39:05,829 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:39:05,829 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2404a12f90>, reason: session-pool-terminated
2025-12-09 13:39:05,829 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f2404a12f90> is no longer active. Current active count 0.
2025-12-09 13:39:05,830 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, fed2104d-d7f7-4e29-821e-3a7758f0c505, ghrun-kvxh57fnmu.auto.internal:12330): creating call state
2025-12-09 13:39:05,830 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, fed2104d-d7f7-4e29-821e-3a7758f0c505, ghrun-kvxh57fnmu.auto.internal:12330): request = { session_id: "ydb://session/3?node_id=50003&id=MmQyY2QxMjItZjMyZGI4NjMtNWI4M2JhYjktNGVmNmJiNjQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:39:05,831 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:39:05,834 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, fed2104d-d7f7-4e29-821e-3a7758f0c505, ghrun-kvxh57fnmu.auto.internal:12330): on response callback started
2025-12-09 13:39:05,835 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, fed2104d-d7f7-4e29-821e-3a7758f0c505, ghrun-kvxh57fnmu.auto.internal:12330): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:39:05,835 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, fed2104d-d7f7-4e29-821e-3a7758f0c505, ghrun-kvxh57fnmu.auto.internal:12330): on response callback success
2025-12-09 13:39:05,836 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2404a12f90>
2025-12-09 13:39:05,836 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:39:05,836 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:39:05,836 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:39:05,837 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:11395
2025-12-09 13:39:05,837 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:12330
2025-12-09 13:39:05,846 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:39:05,850 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:39:05,851 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:39:05,852 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-09 13:39:05,856 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765287545853820&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:39:05,856 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-09 13:39:06,360 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:39:06,360 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-09 13:39:06,363 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:39:07,364 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:12330/4
2025-12-09 13:39:07,364 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 3
2025-12-09 13:39:07,374 - INFO - ya.test.3104096  - pytest_runtest_teardown: Test teardown
