2025-12-09 13:37:58,263 - INFO - ya.test.3104096  - pytest_runtest_setup: ####################################################################################################
2025-12-09 13:37:58,264 - INFO - ya.test.3104096  - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--false]
2025-12-09 13:37:58,264 - INFO - ya.test.3104096  - pytest_runtest_setup: ####################################################################################################
2025-12-09 13:37:58,265 - INFO - ya.test.3104096  - pytest_runtest_setup: Test setup
2025-12-09 13:37:58,266 - INFO - ydb.tests.library.fixtures.3104096  - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-12-09 13:38:05,274 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:17492/1
2025-12-09 13:38:05,278 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27025
2025-12-09 13:38:05,279 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15607
2025-12-09 13:38:05,279 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28571
2025-12-09 13:38:05,279 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2053
2025-12-09 13:38:05,279 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27290
2025-12-09 13:38:05,279 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1167
2025-12-09 13:38:05,280 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27867
2025-12-09 13:38:05,281 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16504
2025-12-09 13:38:05,281 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1101
2025-12-09 13:38:05,281 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5231
2025-12-09 13:38:05,281 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26308
2025-12-09 13:38:05,282 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11280
2025-12-09 13:38:05,282 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18392
2025-12-09 13:38:05,282 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32759
2025-12-09 13:38:05,282 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24673
2025-12-09 13:38:05,284 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25352
2025-12-09 13:38:05,284 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14430
2025-12-09 13:38:05,284 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61151
2025-12-09 13:38:05,284 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22153
2025-12-09 13:38:05,286 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24207
2025-12-09 13:38:05,286 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19605
2025-12-09 13:38:05,286 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24743
2025-12-09 13:38:05,287 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64116
2025-12-09 13:38:05,288 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/65210
2025-12-09 13:38:05,288 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25811
2025-12-09 13:38:05,288 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28001
2025-12-09 13:38:05,289 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28383
2025-12-09 13:38:05,289 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29258
2025-12-09 13:38:05,289 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31928
2025-12-09 13:38:05,289 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5926
2025-12-09 13:38:05,290 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10888
2025-12-09 13:38:05,290 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8427
2025-12-09 13:38:05,290 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20988
2025-12-09 13:38:05,290 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17440
2025-12-09 13:38:05,290 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6994
2025-12-09 13:38:05,290 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19446
2025-12-09 13:38:05,291 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27473
2025-12-09 13:38:05,294 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25095
2025-12-09 13:38:05,294 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63804
2025-12-09 13:38:05,295 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25122
2025-12-09 13:38:05,296 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19272
2025-12-09 13:38:05,296 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6143
2025-12-09 13:38:05,296 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30187
2025-12-09 13:38:05,296 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28120
2025-12-09 13:38:05,297 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4575
2025-12-09 13:38:05,298 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6216
2025-12-09 13:38:05,298 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28052
2025-12-09 13:38:05,300 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9095
2025-12-09 13:38:05,301 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29908
2025-12-09 13:38:05,302 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21145
2025-12-09 13:38:05,302 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16506
2025-12-09 13:38:05,303 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10483
2025-12-09 13:38:05,303 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19940
2025-12-09 13:38:05,304 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17492
2025-12-09 13:38:05,304 - DEBUG - library.python.filelock.3104096  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18412
2025-12-09 13:38:05,377 - 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:05,378 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26595
2025-12-09 13:38:05,379 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26596
2025-12-09 13:38:05,380 - INFO - ydb.tests.library.fixtures.3104096  - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-12-09 13:38:05,380 - INFO - ydb.tests.library.fixtures.3104096  - ydb_cluster: setup ydb_cluster as local
2025-12-09 13:38:05,396 - DEBUG - ydb.tests.library.harness.kikimr_runner.3104096  - start: Working directory: /home/runner/.ya/build/build_root/d95e/001f29/r3tmp/kikimr_cluster_4u1drr0m
2025-12-09 13:38:05,436 - 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:05,436 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29535
2025-12-09 13:38:05,436 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2188
2025-12-09 13:38:05,442 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14879
2025-12-09 13:38:05,442 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19600
2025-12-09 13:38:05,448 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:05,449 - 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--false/cluster/node_1/logfile_7a8hldmz.log --grpc-port=29535 --mon-port=2188 --ic-port=26596
2025-12-09 13:38:05,449 - DEBUG - ydb.tests.library.harness.kikimr_runner.3104096  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:29535/1, disk_size 68719476736
2025-12-09 13:38:05,449 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:05,450 - 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--false/cluster/node_1/logfile_7a8hldmz.log --grpc-port=29535 --mon-port=2188 --ic-port=26596
2025-12-09 13:38:05,451 - 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--false/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--false/cluster/node_1/logfile_7a8hldmz.log', '--grpc-port=29535', '--mon-port=2188', '--ic-port=26596')' 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--false/cluster/node_1' (no wait)
2025-12-09 13:38:05,454 - DEBUG - ya.test.3104096  - execute: Command pid: 3251442
2025-12-09 13:38:05,455 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:29535/1
2025-12-09 13:38:05,461 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.30
2025-12-09 13:38:05,465 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.30
2025-12-09 13:38:05,465 - 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:05,470 - DEBUG - ya.test.3104096  - execute: Command pid: 3251444
2025-12-09 13:38:06,540 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) rc: 0
2025-12-09 13:38:06,541 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) elapsed time (sec): 1.0699853897094727
2025-12-09 13:38:06,543 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) idrss: 0
2025-12-09 13:38:06,543 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) inblock: 0
2025-12-09 13:38:06,543 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) isrss: 0
2025-12-09 13:38:06,545 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) ixrss: 0
2025-12-09 13:38:06,545 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) majflt: 1
2025-12-09 13:38:06,545 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) maxrss: 547320
2025-12-09 13:38:06,545 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) minflt: 16267
2025-12-09 13:38:06,547 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) msgrcv: 0
2025-12-09 13:38:06,547 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) msgsnd: 0
2025-12-09 13:38:06,547 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) nivcsw: 48
2025-12-09 13:38:06,548 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) nsignals: 0
2025-12-09 13:38:06,548 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) nswap: 0
2025-12-09 13:38:06,548 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) nvcsw: 28
2025-12-09 13:38:06,548 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) oublock: 8
2025-12-09 13:38:06,549 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) stime: 0.8933209999999999
2025-12-09 13:38:06,550 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) utime: 0.124301
2025-12-09 13:38:06,551 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) wtime: 1.073
2025-12-09 13:38:06,551 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) 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:06,552 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3251444) errors:

2025-12-09 13:38:06,553 - 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:10,784 - 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:10,786 - 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:29535', '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: 26596\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-09 13:38:10,788 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.31
2025-12-09 13:38:10,790 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.31
2025-12-09 13:38:10,791 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29535', '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: 26596\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-09 13:38:10,793 - DEBUG - ya.test.3104096  - execute: Command pid: 3252995
2025-12-09 13:38:11,449 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) rc: 0
2025-12-09 13:38:11,450 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) elapsed time (sec): 0.6554312705993652
2025-12-09 13:38:11,451 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) idrss: 0
2025-12-09 13:38:11,451 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) inblock: 0
2025-12-09 13:38:11,452 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) isrss: 0
2025-12-09 13:38:11,452 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) ixrss: 0
2025-12-09 13:38:11,453 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) majflt: 0
2025-12-09 13:38:11,453 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) maxrss: 555844
2025-12-09 13:38:11,454 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) minflt: 18410
2025-12-09 13:38:11,454 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) msgrcv: 0
2025-12-09 13:38:11,454 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) msgsnd: 0
2025-12-09 13:38:11,455 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) nivcsw: 41
2025-12-09 13:38:11,456 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) nsignals: 0
2025-12-09 13:38:11,456 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) nswap: 0
2025-12-09 13:38:11,457 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) nvcsw: 148
2025-12-09 13:38:11,457 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) oublock: 8
2025-12-09 13:38:11,458 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) stime: 0.49765
2025-12-09 13:38:11,458 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) utime: 0.10749199999999999
2025-12-09 13:38:11,459 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) wtime: 0.657
2025-12-09 13:38:11,459 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-09 13:38:11,459 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3252995) errors:

2025-12-09 13:38:11,460 - 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:11,460 - 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:29535', '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:11,462 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.32
2025-12-09 13:38:11,464 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.32
2025-12-09 13:38:11,465 - DEBUG - ya.test.3104096  - execute: Executing '['/home/runner/.ya/build/build_root/d95e/001f29/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29535', '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:11,466 - DEBUG - ya.test.3104096  - execute: Command pid: 3253323
2025-12-09 13:38:12,917 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) rc: 0
2025-12-09 13:38:12,918 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) elapsed time (sec): 1.4501705169677734
2025-12-09 13:38:12,918 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) idrss: 0
2025-12-09 13:38:12,918 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) inblock: 0
2025-12-09 13:38:12,918 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) isrss: 0
2025-12-09 13:38:12,919 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) ixrss: 0
2025-12-09 13:38:12,919 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) majflt: 0
2025-12-09 13:38:12,919 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) maxrss: 555812
2025-12-09 13:38:12,920 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) minflt: 18411
2025-12-09 13:38:12,921 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) msgrcv: 0
2025-12-09 13:38:12,922 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) msgsnd: 0
2025-12-09 13:38:12,922 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) nivcsw: 786
2025-12-09 13:38:12,923 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) nsignals: 0
2025-12-09 13:38:12,923 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) nswap: 0
2025-12-09 13:38:12,923 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) nvcsw: 162
2025-12-09 13:38:12,924 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) oublock: 8
2025-12-09 13:38:12,924 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) stime: 1.248893
2025-12-09 13:38:12,924 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) utime: 0.119128
2025-12-09 13:38:12,925 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) wtime: 1.451
2025-12-09 13:38:12,926 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-09 13:38:12,927 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3253323) errors:

2025-12-09 13:38:12,927 - 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:12,946 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - __run: Cluster started and initialized
2025-12-09 13:38:12,958 - 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--false])
2025-12-09 13:38:12,958 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:38:14,044 - 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:14,050 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10423
2025-12-09 13:38:14,051 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21294
2025-12-09 13:38:14,059 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24370
2025-12-09 13:38:14,060 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25480
2025-12-09 13:38:14,068 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16619
2025-12-09 13:38:14,078 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:14,080 - 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:29535 --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--false/cluster/slot_1/logfile_smluxkc7.log --grpc-port=10423 --mon-port=21294 --ic-port=24370
2025-12-09 13:38:14,080 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:14,084 - 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:29535 --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--false/cluster/slot_1/logfile_smluxkc7.log --grpc-port=10423 --mon-port=21294 --ic-port=24370
2025-12-09 13:38:14,096 - 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--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29535', '--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--false/cluster/slot_1/logfile_smluxkc7.log', '--grpc-port=10423', '--mon-port=21294', '--ic-port=24370')' 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--false/cluster/slot_1' (no wait)
2025-12-09 13:38:14,097 - DEBUG - ya.test.3104096  - execute: Command pid: 3254531
2025-12-09 13:38:14,099 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:10423/1
2025-12-09 13:38:14,109 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.33
2025-12-09 13:38:14,110 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.33
2025-12-09 13:38:14,112 - 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:14,113 - DEBUG - ya.test.3104096  - execute: Command pid: 3254574
2025-12-09 13:38:17,257 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) rc: 0
2025-12-09 13:38:17,257 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) elapsed time (sec): 3.1384146213531494
2025-12-09 13:38:17,258 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) idrss: 0
2025-12-09 13:38:17,258 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) inblock: 0
2025-12-09 13:38:17,258 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) isrss: 0
2025-12-09 13:38:17,258 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) ixrss: 0
2025-12-09 13:38:17,259 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) majflt: 1
2025-12-09 13:38:17,259 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) maxrss: 547316
2025-12-09 13:38:17,259 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) minflt: 16439
2025-12-09 13:38:17,259 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) msgrcv: 0
2025-12-09 13:38:17,260 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) msgsnd: 0
2025-12-09 13:38:17,260 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) nivcsw: 404
2025-12-09 13:38:17,260 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) nsignals: 0
2025-12-09 13:38:17,260 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) nswap: 0
2025-12-09 13:38:17,261 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) nvcsw: 46
2025-12-09 13:38:17,261 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) oublock: 8
2025-12-09 13:38:17,264 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) stime: 2.144461
2025-12-09 13:38:17,265 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) utime: 0.10318899999999999
2025-12-09 13:38:17,265 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) wtime: 3.145
2025-12-09 13:38:17,265 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) 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:17,265 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3254574) errors:

2025-12-09 13:38:17,265 - 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:17,266 - 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:17,268 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:17,269 - 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:17,269 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:38:17,274 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:38:17,274 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f24049c15f0 state=pending>
2025-12-09 13:38:17,274 - 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:17,274 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,274 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,275 - 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:17,278 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:17,279 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 3e7fdb45-8d51-4d59-8f98-fc1db4ef46f4, localhost:29535): creating call state
2025-12-09 13:38:17,280 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 3e7fdb45-8d51-4d59-8f98-fc1db4ef46f4, localhost:29535): request = { database: "/Root/users/database" }
2025-12-09 13:38:17,284 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 3e7fdb45-8d51-4d59-8f98-fc1db4ef46f4, localhost:29535): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 10423 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:17,285 - 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:10423, location , ssl: False>]>
2025-12-09 13:38:17,285 - 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:17,309 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:17,310 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,310 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, 1846e625-8b27-424f-9cb2-bc688bba99e2, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:17,311 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, 1846e625-8b27-424f-9cb2-bc688bba99e2, ghrun-kvxh57fnmu.auto.internal:10423): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:17,624 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 1846e625-8b27-424f-9cb2-bc688bba99e2, ghrun-kvxh57fnmu.auto.internal:10423): on response callback started
2025-12-09 13:38:17,625 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, 1846e625-8b27-424f-9cb2-bc688bba99e2, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" } } } }
2025-12-09 13:38:17,625 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,625 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,625 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 1846e625-8b27-424f-9cb2-bc688bba99e2, ghrun-kvxh57fnmu.auto.internal:10423): on response callback success
2025-12-09 13:38:17,626 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, cec7e48c-42b2-4393-932d-f7968054a447, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:17,627 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, cec7e48c-42b2-4393-932d-f7968054a447, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:17,641 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, cec7e48c-42b2-4393-932d-f7968054a447, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:17,642 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,692 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, dafbf9f0-7ac6-464d-89b2-ed4a88dc13d7, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:17,694 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, dafbf9f0-7ac6-464d-89b2-ed4a88dc13d7, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:17,698 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, dafbf9f0-7ac6-464d-89b2-ed4a88dc13d7, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:17,699 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,797 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 7700efac-f4f1-4fe6-bece-723f32f3609f, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:17,797 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 7700efac-f4f1-4fe6-bece-723f32f3609f, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:17,802 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 7700efac-f4f1-4fe6-bece-723f32f3609f, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:17,802 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:17,987 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, d4a7ad35-0b44-4c8d-8da6-c3e4dfc4b7de, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:17,987 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, d4a7ad35-0b44-4c8d-8da6-c3e4dfc4b7de, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:17,992 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, d4a7ad35-0b44-4c8d-8da6-c3e4dfc4b7de, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:17,992 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:18,356 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 5929e029-90bb-4741-846c-536bd6cd2a92, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:18,356 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 5929e029-90bb-4741-846c-536bd6cd2a92, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:18,362 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 5929e029-90bb-4741-846c-536bd6cd2a92, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:18,363 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:19,092 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, d5e96442-6e51-4fd6-9834-b50a5b21dfdb, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:19,093 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, d5e96442-6e51-4fd6-9834-b50a5b21dfdb, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:19,136 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, d5e96442-6e51-4fd6-9834-b50a5b21dfdb, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:19,137 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:19,137 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:38:19,138 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 2c7e510d-61a7-4dd2-96ed-bf5c2ded7fd0, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:19,138 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 2c7e510d-61a7-4dd2-96ed-bf5c2ded7fd0, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:19,161 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 2c7e510d-61a7-4dd2-96ed-bf5c2ded7fd0, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:19,161 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:19,162 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:38:19,163 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, aff87a80-8747-461f-9540-fafd11c9d797, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:19,164 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, aff87a80-8747-461f-9540-fafd11c9d797, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:19,188 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, aff87a80-8747-461f-9540-fafd11c9d797, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:38:19,189 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:19,189 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:38:19,190 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, e5fa6d01-c5d4-4fd9-a956-61256eb7a4e4, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:19,190 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, e5fa6d01-c5d4-4fd9-a956-61256eb7a4e4, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" 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:19,221 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, e5fa6d01-c5d4-4fd9-a956-61256eb7a4e4, ghrun-kvxh57fnmu.auto.internal:10423): 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:19,222 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:19,222 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:38:19,223 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, 21eab09c-4c2c-47a3-9911-b62c8a32268f, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:19,224 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, 21eab09c-4c2c-47a3-9911-b62c8a32268f, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:19,238 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, 21eab09c-4c2c-47a3-9911-b62c8a32268f, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:19,239 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:19,243 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:38:19,243 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:38:19,243 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:38:19,243 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:38:19,244 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f24049c1280>, reason: session-pool-terminated
2025-12-09 13:38:19,245 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f24049c1280> is no longer active. Current active count 0.
2025-12-09 13:38:19,246 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, 6152102a-5984-46fa-9559-6f17444161e5, ghrun-kvxh57fnmu.auto.internal:10423): creating call state
2025-12-09 13:38:19,247 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, 6152102a-5984-46fa-9559-6f17444161e5, ghrun-kvxh57fnmu.auto.internal:10423): request = { session_id: "ydb://session/3?node_id=50000&id=ZGFhMGM0ZjgtYjY5NWEwNGMtNDIxMGU2NjAtZGIyNGNjZjQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:19,248 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f24049c1280>
2025-12-09 13:38:19,248 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:38:19,249 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, 6152102a-5984-46fa-9559-6f17444161e5, ghrun-kvxh57fnmu.auto.internal:10423): on response callback started
2025-12-09 13:38:19,249 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:38:19,250 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, 6152102a-5984-46fa-9559-6f17444161e5, ghrun-kvxh57fnmu.auto.internal:10423): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:19,251 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:38:19,252 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, 6152102a-5984-46fa-9559-6f17444161e5, ghrun-kvxh57fnmu.auto.internal:10423): on response callback success
2025-12-09 13:38:19,253 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:19,253 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:10423
2025-12-09 13:38:19,256 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:38:19,256 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:38:19,257 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:38:19,257 - 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:19,262 - 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=1765287499259666&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:38:19,274 - 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:19,778 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:38:19,779 - 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:19,783 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:38:20,784 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:10423/1
2025-12-09 13:38:20,785 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 0
2025-12-09 13:38:20,785 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:38:21,298 - 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:21,306 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19245
2025-12-09 13:38:21,308 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23407
2025-12-09 13:38:21,309 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26379
2025-12-09 13:38:21,310 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29841
2025-12-09 13:38:21,312 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26042
2025-12-09 13:38:21,314 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:21,314 - 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:29535 --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--false/cluster/slot_2/logfile_b_fzqo5n.log --grpc-port=19245 --mon-port=23407 --ic-port=26379
2025-12-09 13:38:21,315 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:21,316 - 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:29535 --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--false/cluster/slot_2/logfile_b_fzqo5n.log --grpc-port=19245 --mon-port=23407 --ic-port=26379
2025-12-09 13:38:21,319 - 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--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29535', '--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--false/cluster/slot_2/logfile_b_fzqo5n.log', '--grpc-port=19245', '--mon-port=23407', '--ic-port=26379')' 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--false/cluster/slot_2' (no wait)
2025-12-09 13:38:21,321 - DEBUG - ya.test.3104096  - execute: Command pid: 3256957
2025-12-09 13:38:21,321 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:19245/2
2025-12-09 13:38:21,325 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.34
2025-12-09 13:38:21,326 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.34
2025-12-09 13:38:21,326 - 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:21,328 - DEBUG - ya.test.3104096  - execute: Command pid: 3256959
2025-12-09 13:38:23,181 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) rc: 0
2025-12-09 13:38:23,181 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) elapsed time (sec): 1.8522710800170898
2025-12-09 13:38:23,182 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) idrss: 0
2025-12-09 13:38:23,182 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) inblock: 0
2025-12-09 13:38:23,182 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) isrss: 0
2025-12-09 13:38:23,182 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) ixrss: 0
2025-12-09 13:38:23,182 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) majflt: 1
2025-12-09 13:38:23,183 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) maxrss: 547312
2025-12-09 13:38:23,183 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) minflt: 16276
2025-12-09 13:38:23,183 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) msgrcv: 0
2025-12-09 13:38:23,183 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) msgsnd: 0
2025-12-09 13:38:23,183 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) nivcsw: 213
2025-12-09 13:38:23,195 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) nsignals: 0
2025-12-09 13:38:23,198 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) nswap: 0
2025-12-09 13:38:23,198 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) nvcsw: 26
2025-12-09 13:38:23,201 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) oublock: 8
2025-12-09 13:38:23,201 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) stime: 1.601435
2025-12-09 13:38:23,201 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) utime: 0.124622
2025-12-09 13:38:23,203 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) wtime: 1.854
2025-12-09 13:38:23,203 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) 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:23,209 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3256959) errors:

2025-12-09 13:38:23,209 - 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:23,209 - 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:23,211 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:23,212 - 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:23,213 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:38:23,218 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:38:23,218 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f2402d7b490 state=pending>
2025-12-09 13:38:23,219 - 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:23,219 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:23,219 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:23,219 - 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:23,221 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:23,221 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 02a51d7a-7f46-415e-a44b-b0e0a06f21ba, localhost:29535): creating call state
2025-12-09 13:38:23,222 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 02a51d7a-7f46-415e-a44b-b0e0a06f21ba, localhost:29535): request = { database: "/Root/users/database" }
2025-12-09 13:38:23,226 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 02a51d7a-7f46-415e-a44b-b0e0a06f21ba, localhost:29535): 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:23,228 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:29535". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-09 13:38:23,229 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:25,280 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:25,280 - 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:25,286 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:25,287 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 3ddc68a8-7355-4ad7-b551-669e18844e89, localhost:29535): creating call state
2025-12-09 13:38:25,288 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 3ddc68a8-7355-4ad7-b551-669e18844e89, localhost:29535): request = { database: "/Root/users/database" }
2025-12-09 13:38:25,291 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 3ddc68a8-7355-4ad7-b551-669e18844e89, localhost:29535): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 19245 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:25,292 - 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:19245, location , ssl: False>]>
2025-12-09 13:38:25,294 - 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:25,307 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:25,308 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:25,308 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, fd26931c-9d9a-4994-a3df-cb9f5630b76b, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:25,309 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, fd26931c-9d9a-4994-a3df-cb9f5630b76b, ghrun-kvxh57fnmu.auto.internal:19245): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:25,576 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, fd26931c-9d9a-4994-a3df-cb9f5630b76b, ghrun-kvxh57fnmu.auto.internal:19245): on response callback started
2025-12-09 13:38:25,576 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, fd26931c-9d9a-4994-a3df-cb9f5630b76b, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" } } } }
2025-12-09 13:38:25,577 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:25,578 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:25,578 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, fd26931c-9d9a-4994-a3df-cb9f5630b76b, ghrun-kvxh57fnmu.auto.internal:19245): on response callback success
2025-12-09 13:38:25,580 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 8d2d19ff-755e-4c95-bfb2-29be29fdd6e3, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:25,581 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 8d2d19ff-755e-4c95-bfb2-29be29fdd6e3, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" 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:25,606 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 8d2d19ff-755e-4c95-bfb2-29be29fdd6e3, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:25,607 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:25,653 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, e1ea59a8-9848-4d49-b138-adff971e17b7, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:25,654 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, e1ea59a8-9848-4d49-b138-adff971e17b7, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" 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:25,659 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, e1ea59a8-9848-4d49-b138-adff971e17b7, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:25,659 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:25,760 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, 51861e30-bef7-4247-a699-fbaf4774bbe6, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:25,761 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, 51861e30-bef7-4247-a699-fbaf4774bbe6, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" 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:25,767 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, 51861e30-bef7-4247-a699-fbaf4774bbe6, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-09 13:38:25,767 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:25,966 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, a2aa3711-df3b-4ef0-b216-376c6a84010d, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:25,967 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, a2aa3711-df3b-4ef0-b216-376c6a84010d, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" 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:26,019 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, a2aa3711-df3b-4ef0-b216-376c6a84010d, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:26,020 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:26,020 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:38:26,021 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, a8366910-94c6-4bb9-bc71-825577790177, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:26,021 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, a8366910-94c6-4bb9-bc71-825577790177, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" 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:26,048 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, a8366910-94c6-4bb9-bc71-825577790177, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:26,050 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:26,052 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:38:26,053 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, ac6027ed-07d9-4815-bd9e-8ffc8da45cbc, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:26,053 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, ac6027ed-07d9-4815-bd9e-8ffc8da45cbc, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" 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:26,259 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, ac6027ed-07d9-4815-bd9e-8ffc8da45cbc, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:38:26,260 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:26,260 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:38:26,261 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 26f34bac-73f8-4048-850b-1a867d74e311, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:26,261 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 26f34bac-73f8-4048-850b-1a867d74e311, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" 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:26,307 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 26f34bac-73f8-4048-850b-1a867d74e311, ghrun-kvxh57fnmu.auto.internal:19245): 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:26,308 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:26,309 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:38:26,310 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, a9816c64-3913-41c0-875c-b23cd040e001, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:26,311 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, a9816c64-3913-41c0-875c-b23cd040e001, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:26,328 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, a9816c64-3913-41c0-875c-b23cd040e001, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:26,329 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:26,329 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:38:26,329 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:38:26,330 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:38:26,330 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2402d7a2c0>, reason: session-pool-terminated
2025-12-09 13:38:26,330 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f2402d7a2c0> is no longer active. Current active count 0.
2025-12-09 13:38:26,330 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:38:26,331 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, a3810113-1803-48f8-b1bf-59ffeefbeccd, ghrun-kvxh57fnmu.auto.internal:19245): creating call state
2025-12-09 13:38:26,333 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, a3810113-1803-48f8-b1bf-59ffeefbeccd, ghrun-kvxh57fnmu.auto.internal:19245): request = { session_id: "ydb://session/3?node_id=50001&id=ZTg4MTJiODUtNmFmMzljM2MtM2UyMTFmNTItMTRjNDVkNDg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:26,334 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2402d7a2c0>
2025-12-09 13:38:26,335 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:38:26,335 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:38:26,335 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:38:26,335 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:26,335 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:19245
2025-12-09 13:38:26,336 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, a3810113-1803-48f8-b1bf-59ffeefbeccd, ghrun-kvxh57fnmu.auto.internal:19245): on response callback started
2025-12-09 13:38:26,336 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:38:26,337 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, a3810113-1803-48f8-b1bf-59ffeefbeccd, ghrun-kvxh57fnmu.auto.internal:19245): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:26,337 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, a3810113-1803-48f8-b1bf-59ffeefbeccd, ghrun-kvxh57fnmu.auto.internal:19245): on response callback success
2025-12-09 13:38:26,338 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:38:26,338 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:38:26,339 - 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:26,343 - 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=1765287506341651&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:38:26,344 - 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:26,847 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:38:26,848 - 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:26,851 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:38:27,852 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:19245/2
2025-12-09 13:38:27,852 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 1
2025-12-09 13:38:27,853 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:38:28,366 - 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:28,367 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11588
2025-12-09 13:38:28,368 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7096
2025-12-09 13:38:28,369 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21349
2025-12-09 13:38:28,370 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2166
2025-12-09 13:38:28,371 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2167
2025-12-09 13:38:28,371 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2284
2025-12-09 13:38:28,373 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:28,374 - 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:29535 --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--false/cluster/slot_3/logfile_9udcgoc3.log --grpc-port=11588 --mon-port=7096 --ic-port=21349
2025-12-09 13:38:28,375 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:28,377 - 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:29535 --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--false/cluster/slot_3/logfile_9udcgoc3.log --grpc-port=11588 --mon-port=7096 --ic-port=21349
2025-12-09 13:38:28,380 - 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--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29535', '--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--false/cluster/slot_3/logfile_9udcgoc3.log', '--grpc-port=11588', '--mon-port=7096', '--ic-port=21349')' 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--false/cluster/slot_3' (no wait)
2025-12-09 13:38:28,382 - DEBUG - ya.test.3104096  - execute: Command pid: 3261295
2025-12-09 13:38:28,383 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:11588/3
2025-12-09 13:38:28,385 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.35
2025-12-09 13:38:28,390 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.35
2025-12-09 13:38:28,392 - 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:28,393 - DEBUG - ya.test.3104096  - execute: Command pid: 3261298
2025-12-09 13:38:28,753 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) rc: 0
2025-12-09 13:38:28,754 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) elapsed time (sec): 0.358323335647583
2025-12-09 13:38:28,754 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) idrss: 0
2025-12-09 13:38:28,754 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) inblock: 0
2025-12-09 13:38:28,754 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) isrss: 0
2025-12-09 13:38:28,755 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) ixrss: 0
2025-12-09 13:38:28,755 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) majflt: 0
2025-12-09 13:38:28,755 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) maxrss: 547332
2025-12-09 13:38:28,756 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) minflt: 16264
2025-12-09 13:38:28,756 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) msgrcv: 0
2025-12-09 13:38:28,756 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) msgsnd: 0
2025-12-09 13:38:28,756 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) nivcsw: 8
2025-12-09 13:38:28,757 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) nsignals: 0
2025-12-09 13:38:28,757 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) nswap: 0
2025-12-09 13:38:28,758 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) nvcsw: 13
2025-12-09 13:38:28,758 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) oublock: 8
2025-12-09 13:38:28,758 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) stime: 0.193138
2025-12-09 13:38:28,759 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) utime: 0.116688
2025-12-09 13:38:28,759 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) wtime: 0.36
2025-12-09 13:38:28,760 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) 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:28,761 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3261298) errors:

2025-12-09 13:38:28,761 - 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:28,761 - 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:28,763 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:28,764 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:38:28,764 - 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:28,765 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:38:28,768 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f2402d84380 state=pending>
2025-12-09 13:38:28,769 - 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:28,770 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:28,770 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:28,771 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:28,771 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, 4868ac7d-4e80-4ad9-bc85-2bd0542c91c2, localhost:29535): creating call state
2025-12-09 13:38:28,772 - 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:28,772 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, 4868ac7d-4e80-4ad9-bc85-2bd0542c91c2, localhost:29535): request = { database: "/Root/users/database" }
2025-12-09 13:38:28,775 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, 4868ac7d-4e80-4ad9-bc85-2bd0542c91c2, localhost:29535): 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:28,776 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:29535". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-09 13:38:28,776 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:30,806 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:30,807 - 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:30,811 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:30,812 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, c168494f-ca26-426c-a324-7622958417c3, localhost:29535): creating call state
2025-12-09 13:38:30,812 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, c168494f-ca26-426c-a324-7622958417c3, localhost:29535): request = { database: "/Root/users/database" }
2025-12-09 13:38:30,815 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, c168494f-ca26-426c-a324-7622958417c3, localhost:29535): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 11588 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:38:30,815 - 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:11588, location , ssl: False>]>
2025-12-09 13:38:30,816 - 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:30,823 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:30,824 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:30,825 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, 369f29af-f2d9-4426-baae-65c8a23af648, ghrun-kvxh57fnmu.auto.internal:11588): creating call state
2025-12-09 13:38:30,825 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, 369f29af-f2d9-4426-baae-65c8a23af648, ghrun-kvxh57fnmu.auto.internal:11588): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:30,828 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 369f29af-f2d9-4426-baae-65c8a23af648, ghrun-kvxh57fnmu.auto.internal:11588): on response callback started
2025-12-09 13:38:30,829 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, 369f29af-f2d9-4426-baae-65c8a23af648, ghrun-kvxh57fnmu.auto.internal:11588): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=ZWQ5MDI2ZGYtNjEwMjM0MC0zYjVkMTlhMS03ODFlMWYxZA==" } } } }
2025-12-09 13:38:30,829 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:30,830 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:30,831 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, abdb76be-8a69-4fcd-8966-817abf915f5b, ghrun-kvxh57fnmu.auto.internal:11588): creating call state
2025-12-09 13:38:30,831 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 369f29af-f2d9-4426-baae-65c8a23af648, ghrun-kvxh57fnmu.auto.internal:11588): on response callback success
2025-12-09 13:38:30,832 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, abdb76be-8a69-4fcd-8966-817abf915f5b, ghrun-kvxh57fnmu.auto.internal:11588): request = { session_id: "ydb://session/3?node_id=50002&id=ZWQ5MDI2ZGYtNjEwMjM0MC0zYjVkMTlhMS03ODFlMWYxZA==" 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:30,875 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, abdb76be-8a69-4fcd-8966-817abf915f5b, ghrun-kvxh57fnmu.auto.internal:11588): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:30,875 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:30,876 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:38:30,876 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, dca56c20-48c1-44c6-b2e5-ac134faf754e, ghrun-kvxh57fnmu.auto.internal:11588): creating call state
2025-12-09 13:38:30,877 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, dca56c20-48c1-44c6-b2e5-ac134faf754e, ghrun-kvxh57fnmu.auto.internal:11588): request = { session_id: "ydb://session/3?node_id=50002&id=ZWQ5MDI2ZGYtNjEwMjM0MC0zYjVkMTlhMS03ODFlMWYxZA==" 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:30,908 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, dca56c20-48c1-44c6-b2e5-ac134faf754e, ghrun-kvxh57fnmu.auto.internal:11588): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:30,909 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:30,909 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:38:30,910 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 72081df8-86a8-4a44-9618-98223209e068, ghrun-kvxh57fnmu.auto.internal:11588): creating call state
2025-12-09 13:38:30,911 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 72081df8-86a8-4a44-9618-98223209e068, ghrun-kvxh57fnmu.auto.internal:11588): request = { session_id: "ydb://session/3?node_id=50002&id=ZWQ5MDI2ZGYtNjEwMjM0MC0zYjVkMTlhMS03ODFlMWYxZA==" 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:38:30,946 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 72081df8-86a8-4a44-9618-98223209e068, ghrun-kvxh57fnmu.auto.internal:11588): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:38:30,947 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:30,947 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:38:30,947 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, fff7645b-9e4c-4d18-b03b-133042ab446e, ghrun-kvxh57fnmu.auto.internal:11588): creating call state
2025-12-09 13:38:30,948 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, fff7645b-9e4c-4d18-b03b-133042ab446e, ghrun-kvxh57fnmu.auto.internal:11588): request = { session_id: "ydb://session/3?node_id=50002&id=ZWQ5MDI2ZGYtNjEwMjM0MC0zYjVkMTlhMS03ODFlMWYxZA==" 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:30,982 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, fff7645b-9e4c-4d18-b03b-133042ab446e, ghrun-kvxh57fnmu.auto.internal:11588): 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:38:30,983 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:30,983 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:38:30,984 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, e165b961-6047-48d3-bef2-c46692eb127c, ghrun-kvxh57fnmu.auto.internal:11588): creating call state
2025-12-09 13:38:30,984 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, e165b961-6047-48d3-bef2-c46692eb127c, ghrun-kvxh57fnmu.auto.internal:11588): request = { session_id: "ydb://session/3?node_id=50002&id=ZWQ5MDI2ZGYtNjEwMjM0MC0zYjVkMTlhMS03ODFlMWYxZA==" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:31,000 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, e165b961-6047-48d3-bef2-c46692eb127c, ghrun-kvxh57fnmu.auto.internal:11588): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:31,001 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:31,001 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:38:31,001 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:38:31,001 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:38:31,002 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:38:31,002 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2402d84790>, reason: session-pool-terminated
2025-12-09 13:38:31,002 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f2402d84790> is no longer active. Current active count 0.
2025-12-09 13:38:31,003 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, 15136a46-9b29-4281-afe7-3ecc88b6c8c4, ghrun-kvxh57fnmu.auto.internal:11588): creating call state
2025-12-09 13:38:31,003 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, 15136a46-9b29-4281-afe7-3ecc88b6c8c4, ghrun-kvxh57fnmu.auto.internal:11588): request = { session_id: "ydb://session/3?node_id=50002&id=ZWQ5MDI2ZGYtNjEwMjM0MC0zYjVkMTlhMS03ODFlMWYxZA==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:31,004 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2402d84790>
2025-12-09 13:38:31,004 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:38:31,005 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:38:31,005 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:38:31,005 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, 15136a46-9b29-4281-afe7-3ecc88b6c8c4, ghrun-kvxh57fnmu.auto.internal:11588): on response callback started
2025-12-09 13:38:31,006 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:31,006 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, 15136a46-9b29-4281-afe7-3ecc88b6c8c4, ghrun-kvxh57fnmu.auto.internal:11588): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:31,007 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, 15136a46-9b29-4281-afe7-3ecc88b6c8c4, ghrun-kvxh57fnmu.auto.internal:11588): on response callback success
2025-12-09 13:38:31,014 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:11588
2025-12-09 13:38:31,026 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:38:31,027 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:38:31,027 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:38:31,027 - 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:31,033 - 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=1765287511030552&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:38:31,033 - 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:31,541 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:38:31,541 - 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:31,543 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:38:34,544 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:11588/3
2025-12-09 13:38:34,545 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 2
2025-12-09 13:38:34,545 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create_database
2025-12-09 13:38:35,054 - 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:35,054 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1113
2025-12-09 13:38:35,055 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3977
2025-12-09 13:38:35,055 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11082
2025-12-09 13:38:35,055 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2161
2025-12-09 13:38:35,055 - DEBUG - library.python.filelock.3104096  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15076
2025-12-09 13:38:35,056 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:35,056 - 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:29535 --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--false/cluster/slot_4/logfile_o8ciaqyk.log --grpc-port=1113 --mon-port=3977 --ic-port=11082
2025-12-09 13:38:35,056 - 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--false/cluster/kikimr_configs"
2025-12-09 13:38:35,057 - 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:29535 --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--false/cluster/slot_4/logfile_o8ciaqyk.log --grpc-port=1113 --mon-port=3977 --ic-port=11082
2025-12-09 13:38:35,057 - 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--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29535', '--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--false/cluster/slot_4/logfile_o8ciaqyk.log', '--grpc-port=1113', '--mon-port=3977', '--ic-port=11082')' 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--false/cluster/slot_4' (no wait)
2025-12-09 13:38:35,059 - DEBUG - ya.test.3104096  - execute: Command pid: 3266885
2025-12-09 13:38:35,059 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - start: Started node localhost:1113/4
2025-12-09 13:38:35,061 - DEBUG - ya.test.3104096  - _get_command_output_file: Command out will be placed to ydbd.out.36
2025-12-09 13:38:35,062 - DEBUG - ya.test.3104096  - _get_command_output_file: Command err will be placed to ydbd.err.36
2025-12-09 13:38:35,062 - 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:35,063 - DEBUG - ya.test.3104096  - execute: Command pid: 3266909
2025-12-09 13:38:35,394 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) rc: 0
2025-12-09 13:38:35,397 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) elapsed time (sec): 0.3311495780944824
2025-12-09 13:38:35,397 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) idrss: 0
2025-12-09 13:38:35,397 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) inblock: 0
2025-12-09 13:38:35,398 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) isrss: 0
2025-12-09 13:38:35,398 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) ixrss: 0
2025-12-09 13:38:35,398 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) majflt: 0
2025-12-09 13:38:35,398 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) maxrss: 547312
2025-12-09 13:38:35,398 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) minflt: 16429
2025-12-09 13:38:35,398 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) msgrcv: 0
2025-12-09 13:38:35,399 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) msgsnd: 0
2025-12-09 13:38:35,399 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) nivcsw: 7
2025-12-09 13:38:35,399 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) nsignals: 0
2025-12-09 13:38:35,399 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) nswap: 0
2025-12-09 13:38:35,399 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) nvcsw: 15
2025-12-09 13:38:35,399 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) oublock: 8
2025-12-09 13:38:35,399 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) stime: 0.193548
2025-12-09 13:38:35,400 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) utime: 0.08934
2025-12-09 13:38:35,400 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) wtime: 0.332
2025-12-09 13:38:35,400 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) 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:35,401 - DEBUG - ya.test.3104096  - _save_outputs: Command (pid 3266909) errors:

2025-12-09 13:38:35,401 - 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:38:35,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:35,404 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:35,405 - 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:35,405 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table one
2025-12-09 13:38:35,409 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-09 13:38:35,409 - DEBUG - ydb.table.SessionPool.3104096  - subscribe: Subscribe waiter <Future at 0x7f2404ba3030 state=pending>
2025-12-09 13:38:35,410 - 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:35,411 - DEBUG - ydb.table.SessionPool.3104096  - _create: Created session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:35,411 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:35,412 - 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:35,412 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:35,413 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, b3a18347-173f-4d97-ae97-ca30d6637170, localhost:29535): creating call state
2025-12-09 13:38:35,413 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, b3a18347-173f-4d97-ae97-ca30d6637170, localhost:29535): request = { database: "/Root/users/database" }
2025-12-09 13:38:35,415 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, b3a18347-173f-4d97-ae97-ca30d6637170, localhost:29535): 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:35,415 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:29535". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-09 13:38:35,416 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:37,215 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 13:38:37,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:37,221 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.3104096  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-09 13:38:37,222 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ListEndpoints, c083a992-b269-45f9-b5d8-9d1fbf68557b, localhost:29535): creating call state
2025-12-09 13:38:37,222 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ListEndpoints, c083a992-b269-45f9-b5d8-9d1fbf68557b, localhost:29535): request = { database: "/Root/users/database" }
2025-12-09 13:38:37,228 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ListEndpoints, c083a992-b269-45f9-b5d8-9d1fbf68557b, localhost:29535): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kvxh57fnmu.auto.internal" port: 1113 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:38:37,229 - 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:1113, location , ssl: False>]>
2025-12-09 13:38:37,229 - 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:37,240 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:37,240 - DEBUG - ydb.table.SessionPool.3104096  - _prepare: Preparing session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,241 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateSession, 56fa305e-4992-4e8d-ac56-fbb2a70f4085, ghrun-kvxh57fnmu.auto.internal:1113): creating call state
2025-12-09 13:38:37,242 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateSession, 56fa305e-4992-4e8d-ac56-fbb2a70f4085, ghrun-kvxh57fnmu.auto.internal:1113): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:37,244 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 56fa305e-4992-4e8d-ac56-fbb2a70f4085, ghrun-kvxh57fnmu.auto.internal:1113): on response callback started
2025-12-09 13:38:37,245 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateSession, 56fa305e-4992-4e8d-ac56-fbb2a70f4085, ghrun-kvxh57fnmu.auto.internal:1113): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=NDVjOWI1YjQtZmQ4ZTQ4OTEtNWUyMWI0MzAtNGRhZGRlMmY=" } } } }
2025-12-09 13:38:37,246 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,246 - DEBUG - ydb.table.SessionPool.3104096  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,246 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(CreateSession, 56fa305e-4992-4e8d-ac56-fbb2a70f4085, ghrun-kvxh57fnmu.auto.internal:1113): on response callback success
2025-12-09 13:38:37,247 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, acf20fa3-8631-4752-86de-28a661d28c35, ghrun-kvxh57fnmu.auto.internal:1113): creating call state
2025-12-09 13:38:37,248 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, acf20fa3-8631-4752-86de-28a661d28c35, ghrun-kvxh57fnmu.auto.internal:1113): request = { session_id: "ydb://session/3?node_id=50003&id=NDVjOWI1YjQtZmQ4ZTQ4OTEtNWUyMWI0MzAtNGRhZGRlMmY=" 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:37,300 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, acf20fa3-8631-4752-86de-28a661d28c35, ghrun-kvxh57fnmu.auto.internal:1113): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:37,300 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,300 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: create table two
2025-12-09 13:38:37,301 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(CreateTable, c20ee9ff-d18a-41b8-a7de-716b34d8defb, ghrun-kvxh57fnmu.auto.internal:1113): creating call state
2025-12-09 13:38:37,301 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(CreateTable, c20ee9ff-d18a-41b8-a7de-716b34d8defb, ghrun-kvxh57fnmu.auto.internal:1113): request = { session_id: "ydb://session/3?node_id=50003&id=NDVjOWI1YjQtZmQ4ZTQ4OTEtNWUyMWI0MzAtNGRhZGRlMmY=" 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:37,327 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(CreateTable, c20ee9ff-d18a-41b8-a7de-716b34d8defb, ghrun-kvxh57fnmu.auto.internal:1113): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:37,328 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,328 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-09 13:38:37,328 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 33847fbc-511c-4965-bc89-bd3c18ff8c3d, ghrun-kvxh57fnmu.auto.internal:1113): creating call state
2025-12-09 13:38:37,329 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 33847fbc-511c-4965-bc89-bd3c18ff8c3d, ghrun-kvxh57fnmu.auto.internal:1113): request = { session_id: "ydb://session/3?node_id=50003&id=NDVjOWI1YjQtZmQ4ZTQ4OTEtNWUyMWI0MzAtNGRhZGRlMmY=" 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:38:37,406 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 33847fbc-511c-4965-bc89-bd3c18ff8c3d, ghrun-kvxh57fnmu.auto.internal:1113): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-09 13:38:37,407 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,407 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-09 13:38:37,407 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(ExecuteDataQuery, 8172f568-a043-4f5e-9748-88337b526d7e, ghrun-kvxh57fnmu.auto.internal:1113): creating call state
2025-12-09 13:38:37,408 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(ExecuteDataQuery, 8172f568-a043-4f5e-9748-88337b526d7e, ghrun-kvxh57fnmu.auto.internal:1113): request = { session_id: "ydb://session/3?node_id=50003&id=NDVjOWI1YjQtZmQ4ZTQ4OTEtNWUyMWI0MzAtNGRhZGRlMmY=" 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:37,441 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(ExecuteDataQuery, 8172f568-a043-4f5e-9748-88337b526d7e, ghrun-kvxh57fnmu.auto.internal:1113): 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:38:37,442 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,442 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-09 13:38:37,443 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DropTable, cc659e16-9ae8-488f-841c-c21d4c087313, ghrun-kvxh57fnmu.auto.internal:1113): creating call state
2025-12-09 13:38:37,443 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DropTable, cc659e16-9ae8-488f-841c-c21d4c087313, ghrun-kvxh57fnmu.auto.internal:1113): request = { session_id: "ydb://session/3?node_id=50003&id=NDVjOWI1YjQtZmQ4ZTQ4OTEtNWUyMWI0MzAtNGRhZGRlMmY=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-09 13:38:37,457 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DropTable, cc659e16-9ae8-488f-841c-c21d4c087313, ghrun-kvxh57fnmu.auto.internal:1113): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:37,459 - DEBUG - ydb.table.SessionPool.3104096  - put: Put on session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,459 - DEBUG - ydb.table.SessionPool.3104096  - stop: Requested session pool stop.
2025-12-09 13:38:37,459 - DEBUG - ydb.table.SessionPool.3104096  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-09 13:38:37,459 - DEBUG - ydb.table.SessionPool.3104096  - _terminate_event: Terminated session pool.
2025-12-09 13:38:37,460 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroying sessions in active queue
2025-12-09 13:38:37,461 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2404ba35d0>, reason: session-pool-terminated
2025-12-09 13:38:37,461 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Session <ydb.table.Session object at 0x7f2404ba35d0> is no longer active. Current active count 0.
2025-12-09 13:38:37,462 - DEBUG - ydb.connection.3104096  - _prepare_call: RpcState(DeleteSession, 6a8cfe83-16ad-4d90-afec-e4cf917df97c, ghrun-kvxh57fnmu.auto.internal:1113): creating call state
2025-12-09 13:38:37,462 - DEBUG - ydb.connection.3104096  - _log_request: RpcState(DeleteSession, 6a8cfe83-16ad-4d90-afec-e4cf917df97c, ghrun-kvxh57fnmu.auto.internal:1113): request = { session_id: "ydb://session/3?node_id=50003&id=NDVjOWI1YjQtZmQ4ZTQ4OTEtNWUyMWI0MzAtNGRhZGRlMmY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-09 13:38:37,464 - DEBUG - ydb.table.SessionPool.3104096  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2404ba35d0>
2025-12-09 13:38:37,464 - DEBUG - ydb.table.SessionPool.3104096  - stop: Destroyed active sessions
2025-12-09 13:38:37,464 - DEBUG - ydb.topic.3104096  - close: Close topic client
2025-12-09 13:38:37,464 - DEBUG - ydb.topic.3104096  - close: Topic client was closed
2025-12-09 13:38:37,465 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint localhost:29535
2025-12-09 13:38:37,465 - DEBUG - ydb.connection.3104096  - close: Closing channel for endpoint ghrun-kvxh57fnmu.auto.internal:1113
2025-12-09 13:38:37,466 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, 6a8cfe83-16ad-4d90-afec-e4cf917df97c, ghrun-kvxh57fnmu.auto.internal:1113): on response callback started
2025-12-09 13:38:37,467 - INFO - ydb.pool.Discovery.3104096  - run: Successfully terminated discovery process
2025-12-09 13:38:37,468 - DEBUG - ydb.connection.3104096  - _log_response: RpcState(DeleteSession, 6a8cfe83-16ad-4d90-afec-e4cf917df97c, ghrun-kvxh57fnmu.auto.internal:1113): response = { operation { ready: true status: SUCCESS } }
2025-12-09 13:38:37,468 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-09 13:38:37,474 - DEBUG - ydb.connection.3104096  - _on_response_callback: RpcState(DeleteSession, 6a8cfe83-16ad-4d90-afec-e4cf917df97c, ghrun-kvxh57fnmu.auto.internal:1113): on response callback success
2025-12-09 13:38:37,474 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - remove_database: /Root/users/database
2025-12-09 13:38:37,476 - 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:37,481 - 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=1765287517478703&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-09 13:38:37,481 - 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:37,985 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-09 13:38:37,985 - 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:37,988 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.3104096  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-09 13:38:38,989 - INFO - ydb.tests.library.harness.kikimr_runner.3104096  - stop: Stopped node localhost:1113/4
2025-12-09 13:38:38,990 - DEBUG - __tests__.test_dynamic_tenants.3104096  - test_create_and_drop_the_same_tenant2: done 3
2025-12-09 13:38:38,992 - INFO - ya.test.3104096  - pytest_runtest_teardown: Test teardown
