2025-12-04 14:18:21,182 - INFO - ya.test.2824334  - pytest_runtest_setup: ####################################################################################################
2025-12-04 14:18:21,182 - INFO - ya.test.2824334  - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true]
2025-12-04 14:18:21,183 - INFO - ya.test.2824334  - pytest_runtest_setup: ####################################################################################################
2025-12-04 14:18:21,183 - INFO - ya.test.2824334  - pytest_runtest_setup: Test setup
2025-12-04 14:18:21,184 - INFO - ydb.tests.library.fixtures.2824334  - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-12-04 14:18:24,187 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - stop: Stopped node localhost:22903/1
2025-12-04 14:18:24,188 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2732
2025-12-04 14:18:24,188 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17369
2025-12-04 14:18:24,188 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30793
2025-12-04 14:18:24,188 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2193
2025-12-04 14:18:24,189 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11837
2025-12-04 14:18:24,189 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22432
2025-12-04 14:18:24,189 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64710
2025-12-04 14:18:24,189 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2990
2025-12-04 14:18:24,189 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14823
2025-12-04 14:18:24,190 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17468
2025-12-04 14:18:24,190 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28350
2025-12-04 14:18:24,190 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16113
2025-12-04 14:18:24,190 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15599
2025-12-04 14:18:24,190 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4468
2025-12-04 14:18:24,191 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8059
2025-12-04 14:18:24,191 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6110
2025-12-04 14:18:24,191 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12957
2025-12-04 14:18:24,191 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61351
2025-12-04 14:18:24,191 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8827
2025-12-04 14:18:24,192 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25625
2025-12-04 14:18:24,192 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15518
2025-12-04 14:18:24,192 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25350
2025-12-04 14:18:24,202 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5791
2025-12-04 14:18:24,203 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22903
2025-12-04 14:18:24,205 - DEBUG - library.python.filelock.2824334  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7917
2025-12-04 14:18:24,303 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:24,313 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3845
2025-12-04 14:18:24,315 - INFO - ydb.tests.library.fixtures.2824334  - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-12-04 14:18:24,315 - INFO - ydb.tests.library.fixtures.2824334  - ydb_cluster: setup ydb_cluster as local
2025-12-04 14:18:24,323 - DEBUG - ydb.tests.library.harness.kikimr_runner.2824334  - start: Working directory: /home/runner/.ya/build/build_root/l7pa/0027aa/r3tmp/kikimr_cluster_q29muix7
2025-12-04 14:18:24,382 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:24,383 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8705
2025-12-04 14:18:24,383 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30061
2025-12-04 14:18:24,384 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1983
2025-12-04 14:18:24,384 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12765
2025-12-04 14:18:24,385 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:24,385 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile__1ku8spj.log --grpc-port=8705 --mon-port=30061 --ic-port=3845
2025-12-04 14:18:24,385 - DEBUG - ydb.tests.library.harness.kikimr_runner.2824334  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:8705/1, disk_size 68719476736
2025-12-04 14:18:24,386 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:24,386 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile__1ku8spj.log --grpc-port=8705 --mon-port=30061 --ic-port=3845
2025-12-04 14:18:24,387 - DEBUG - ya.test.2824334  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile__1ku8spj.log', '--grpc-port=8705', '--mon-port=30061', '--ic-port=3845')' in '/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-12-04 14:18:24,394 - DEBUG - ya.test.2824334  - execute: Command pid: 2949123
2025-12-04 14:18:24,428 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Started node localhost:8705/1
2025-12-04 14:18:24,440 - DEBUG - ya.test.2824334  - _get_command_output_file: Command out will be placed to ydbd.out.37
2025-12-04 14:18:24,445 - DEBUG - ya.test.2824334  - _get_command_output_file: Command err will be placed to ydbd.err.37
2025-12-04 14:18:24,451 - DEBUG - ya.test.2824334  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:18:24,458 - DEBUG - ya.test.2824334  - execute: Command pid: 2949128
2025-12-04 14:18:25,383 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) rc: 0
2025-12-04 14:18:25,384 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) elapsed time (sec): 0.9215912818908691
2025-12-04 14:18:25,384 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) idrss: 0
2025-12-04 14:18:25,385 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) inblock: 0
2025-12-04 14:18:25,385 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) isrss: 0
2025-12-04 14:18:25,386 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) ixrss: 0
2025-12-04 14:18:25,386 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) majflt: 0
2025-12-04 14:18:25,386 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) maxrss: 546756
2025-12-04 14:18:25,386 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) minflt: 16262
2025-12-04 14:18:25,386 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) msgrcv: 0
2025-12-04 14:18:25,387 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) msgsnd: 0
2025-12-04 14:18:25,387 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) nivcsw: 102
2025-12-04 14:18:25,387 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) nsignals: 0
2025-12-04 14:18:25,387 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) nswap: 0
2025-12-04 14:18:25,387 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) nvcsw: 17
2025-12-04 14:18:25,392 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) oublock: 8
2025-12-04 14:18:25,393 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) stime: 0.26367999999999997
2025-12-04 14:18:25,393 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) utime: 0.143371
2025-12-04 14:18:25,393 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) wtime: 0.923
2025-12-04 14:18:25,393 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 14:18:25,394 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2949128) errors:

2025-12-04 14:18:25,394 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Node 1 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:18:27,780 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:27,781 - DEBUG - ydb.tests.library.harness.kikimr_runner.2824334  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8705', '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: 3845\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-04 14:18:27,782 - DEBUG - ya.test.2824334  - _get_command_output_file: Command out will be placed to ydbd.out.38
2025-12-04 14:18:27,784 - DEBUG - ya.test.2824334  - _get_command_output_file: Command err will be placed to ydbd.err.38
2025-12-04 14:18:27,784 - DEBUG - ya.test.2824334  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8705', '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: 3845\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 14:18:27,785 - DEBUG - ya.test.2824334  - execute: Command pid: 2951754
2025-12-04 14:18:28,247 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) rc: 0
2025-12-04 14:18:28,247 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) elapsed time (sec): 0.458892822265625
2025-12-04 14:18:28,247 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) idrss: 0
2025-12-04 14:18:28,248 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) inblock: 0
2025-12-04 14:18:28,248 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) isrss: 0
2025-12-04 14:18:28,248 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) ixrss: 0
2025-12-04 14:18:28,248 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) majflt: 0
2025-12-04 14:18:28,248 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) maxrss: 555256
2025-12-04 14:18:28,248 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) minflt: 18401
2025-12-04 14:18:28,249 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) msgrcv: 0
2025-12-04 14:18:28,249 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) msgsnd: 0
2025-12-04 14:18:28,249 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) nivcsw: 116
2025-12-04 14:18:28,249 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) nsignals: 0
2025-12-04 14:18:28,249 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) nswap: 0
2025-12-04 14:18:28,249 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) nvcsw: 101
2025-12-04 14:18:28,250 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) oublock: 8
2025-12-04 14:18:28,250 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) stime: 0.26947099999999996
2025-12-04 14:18:28,250 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) utime: 0.110958
2025-12-04 14:18:28,250 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) wtime: 0.462
2025-12-04 14:18:28,255 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-04 14:18:28,255 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2951754) errors:

2025-12-04 14:18:28,256 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:28,256 - DEBUG - ydb.tests.library.harness.kikimr_runner.2824334  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8705', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-04 14:18:28,258 - DEBUG - ya.test.2824334  - _get_command_output_file: Command out will be placed to ydbd.out.39
2025-12-04 14:18:28,259 - DEBUG - ya.test.2824334  - _get_command_output_file: Command err will be placed to ydbd.err.39
2025-12-04 14:18:28,259 - DEBUG - ya.test.2824334  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8705', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 14:18:28,287 - DEBUG - ya.test.2824334  - execute: Command pid: 2952102
2025-12-04 14:18:28,915 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) rc: 0
2025-12-04 14:18:28,915 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) elapsed time (sec): 0.6268343925476074
2025-12-04 14:18:28,916 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) idrss: 0
2025-12-04 14:18:28,916 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) inblock: 0
2025-12-04 14:18:28,916 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) isrss: 0
2025-12-04 14:18:28,916 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) ixrss: 0
2025-12-04 14:18:28,916 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) majflt: 0
2025-12-04 14:18:28,916 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) maxrss: 555264
2025-12-04 14:18:28,917 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) minflt: 18401
2025-12-04 14:18:28,917 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) msgrcv: 0
2025-12-04 14:18:28,917 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) msgsnd: 0
2025-12-04 14:18:28,917 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) nivcsw: 121
2025-12-04 14:18:28,917 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) nsignals: 0
2025-12-04 14:18:28,917 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) nswap: 0
2025-12-04 14:18:28,918 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) nvcsw: 96
2025-12-04 14:18:28,918 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) oublock: 8
2025-12-04 14:18:28,918 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) stime: 0.270016
2025-12-04 14:18:28,918 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) utime: 0.14471499999999998
2025-12-04 14:18:28,919 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) wtime: 0.644
2025-12-04 14:18:28,920 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-04 14:18:28,920 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952102) errors:

2025-12-04 14:18:28,920 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-04 14:18:28,946 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __run: Cluster started and initialized
2025-12-04 14:18:28,962 - INFO - ya.test.2824334  - pytest_runtest_call: Test call (class_name: test_dynamic_tenants.py, test_name: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true])
2025-12-04 14:18:28,962 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 14:18:29,484 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:29,495 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2891
2025-12-04 14:18:29,497 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27188
2025-12-04 14:18:29,497 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12716
2025-12-04 14:18:29,500 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4552
2025-12-04 14:18:29,501 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22264
2025-12-04 14:18:29,506 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:29,514 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_1aqfowf_.log --grpc-port=2891 --mon-port=27188 --ic-port=12716
2025-12-04 14:18:29,514 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:29,516 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_1aqfowf_.log --grpc-port=2891 --mon-port=27188 --ic-port=12716
2025-12-04 14:18:29,517 - DEBUG - ya.test.2824334  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:8705', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_1aqfowf_.log', '--grpc-port=2891', '--mon-port=27188', '--ic-port=12716')' in '/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-12-04 14:18:29,521 - DEBUG - ya.test.2824334  - execute: Command pid: 2952865
2025-12-04 14:18:29,522 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Started node localhost:2891/1
2025-12-04 14:18:29,538 - DEBUG - ya.test.2824334  - _get_command_output_file: Command out will be placed to ydbd.out.40
2025-12-04 14:18:29,539 - DEBUG - ya.test.2824334  - _get_command_output_file: Command err will be placed to ydbd.err.40
2025-12-04 14:18:29,540 - DEBUG - ya.test.2824334  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:18:29,543 - DEBUG - ya.test.2824334  - execute: Command pid: 2952867
2025-12-04 14:18:30,116 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) rc: 0
2025-12-04 14:18:30,116 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) elapsed time (sec): 0.5720994472503662
2025-12-04 14:18:30,116 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) idrss: 0
2025-12-04 14:18:30,116 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) inblock: 0
2025-12-04 14:18:30,117 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) isrss: 0
2025-12-04 14:18:30,117 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) ixrss: 0
2025-12-04 14:18:30,117 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) majflt: 0
2025-12-04 14:18:30,117 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) maxrss: 546756
2025-12-04 14:18:30,117 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) minflt: 16257
2025-12-04 14:18:30,117 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) msgrcv: 0
2025-12-04 14:18:30,118 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) msgsnd: 0
2025-12-04 14:18:30,118 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) nivcsw: 159
2025-12-04 14:18:30,118 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) nsignals: 0
2025-12-04 14:18:30,118 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) nswap: 0
2025-12-04 14:18:30,118 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) nvcsw: 14
2025-12-04 14:18:30,120 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) oublock: 8
2025-12-04 14:18:30,120 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) stime: 0.28281
2025-12-04 14:18:30,120 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) utime: 0.130586
2025-12-04 14:18:30,120 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) wtime: 0.576
2025-12-04 14:18:30,120 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 14:18:30,121 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2952867) errors:

2025-12-04 14:18:30,121 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Node 1 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:18:30,121 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:30,126 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:30,127 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 14:18:30,128 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:30,128 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:18:30,141 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Subscribe waiter <Future at 0x7f2886df1370 state=pending>
2025-12-04 14:18:30,141 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 14:18:30,142 - DEBUG - ydb.table.SessionPool.2824334  - _create: Created session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:30,142 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:30,143 - ERROR - ydb.table.SessionPool.2824334  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:18:30,138 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:30,143 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, 86a92d00-823a-46b3-a37d-61b8a6d425ae, localhost:8705): creating call state
2025-12-04 14:18:30,144 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, 86a92d00-823a-46b3-a37d-61b8a6d425ae, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:30,159 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, 86a92d00-823a-46b3-a37d-61b8a6d425ae, localhost:8705): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-04 14:18:30,164 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:8705". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-04 14:18:30,165 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:31,523 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:31,524 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:31,539 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:31,539 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, c3cdcc3c-e52f-4322-bbe7-1d3089edaaa4, localhost:8705): creating call state
2025-12-04 14:18:31,540 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, c3cdcc3c-e52f-4322-bbe7-1d3089edaaa4, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:31,549 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, c3cdcc3c-e52f-4322-bbe7-1d3089edaaa4, localhost:8705): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 2891 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-12-04 14:18:31,549 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:2891, location , ssl: False>]>
2025-12-04 14:18:31,550 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:31,563 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:31,564 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:31,565 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateSession, 591b9094-d7e8-43d0-8969-5669367fb59a, ghrun-e3snp7fbzi.auto.internal:2891): creating call state
2025-12-04 14:18:31,565 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateSession, 591b9094-d7e8-43d0-8969-5669367fb59a, ghrun-e3snp7fbzi.auto.internal:2891): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:31,572 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, 591b9094-d7e8-43d0-8969-5669367fb59a, ghrun-e3snp7fbzi.auto.internal:2891): on response callback started
2025-12-04 14:18:31,573 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateSession, 591b9094-d7e8-43d0-8969-5669367fb59a, ghrun-e3snp7fbzi.auto.internal:2891): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NTI4ODY4ZjAtYjkzZDczM2UtZWE0ZTZiNmItMmZhYmQzMzg=" } } } }
2025-12-04 14:18:31,574 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:31,574 - DEBUG - ydb.table.SessionPool.2824334  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:31,574 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, 591b9094-d7e8-43d0-8969-5669367fb59a, ghrun-e3snp7fbzi.auto.internal:2891): on response callback success
2025-12-04 14:18:31,575 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, 35e0202d-0956-483f-831d-2c8f243a70a9, ghrun-e3snp7fbzi.auto.internal:2891): creating call state
2025-12-04 14:18:31,582 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, 35e0202d-0956-483f-831d-2c8f243a70a9, ghrun-e3snp7fbzi.auto.internal:2891): request = { session_id: "ydb://session/3?node_id=50000&id=NTI4ODY4ZjAtYjkzZDczM2UtZWE0ZTZiNmItMmZhYmQzMzg=" 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-04 14:18:31,675 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, 35e0202d-0956-483f-831d-2c8f243a70a9, ghrun-e3snp7fbzi.auto.internal:2891): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:31,676 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:31,676 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 14:18:31,677 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, d6c24447-d75a-4dca-8d41-1fdeb56de61a, ghrun-e3snp7fbzi.auto.internal:2891): creating call state
2025-12-04 14:18:31,677 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, d6c24447-d75a-4dca-8d41-1fdeb56de61a, ghrun-e3snp7fbzi.auto.internal:2891): request = { session_id: "ydb://session/3?node_id=50000&id=NTI4ODY4ZjAtYjkzZDczM2UtZWE0ZTZiNmItMmZhYmQzMzg=" 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-04 14:18:31,749 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, d6c24447-d75a-4dca-8d41-1fdeb56de61a, ghrun-e3snp7fbzi.auto.internal:2891): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:31,750 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:31,750 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 14:18:31,751 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, 4ce362cf-52ca-4cc8-9923-d04de163d99d, ghrun-e3snp7fbzi.auto.internal:2891): creating call state
2025-12-04 14:18:31,757 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, 4ce362cf-52ca-4cc8-9923-d04de163d99d, ghrun-e3snp7fbzi.auto.internal:2891): request = { session_id: "ydb://session/3?node_id=50000&id=NTI4ODY4ZjAtYjkzZDczM2UtZWE0ZTZiNmItMmZhYmQzMzg=" 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-04 14:18:31,964 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, 4ce362cf-52ca-4cc8-9923-d04de163d99d, ghrun-e3snp7fbzi.auto.internal:2891): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 14:18:31,980 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:31,981 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 14:18:31,981 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, ecd218c7-8ab3-4943-9c65-1d8dc1be8179, ghrun-e3snp7fbzi.auto.internal:2891): creating call state
2025-12-04 14:18:31,982 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, ecd218c7-8ab3-4943-9c65-1d8dc1be8179, ghrun-e3snp7fbzi.auto.internal:2891): request = { session_id: "ydb://session/3?node_id=50000&id=NTI4ODY4ZjAtYjkzZDczM2UtZWE0ZTZiNmItMmZhYmQzMzg=" 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-04 14:18:32,024 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, ecd218c7-8ab3-4943-9c65-1d8dc1be8179, ghrun-e3snp7fbzi.auto.internal:2891): 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-04 14:18:32,025 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:32,026 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 14:18:32,026 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DropTable, 9809e9e8-2b59-4cca-95cc-8e7a3cb31a26, ghrun-e3snp7fbzi.auto.internal:2891): creating call state
2025-12-04 14:18:32,027 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DropTable, 9809e9e8-2b59-4cca-95cc-8e7a3cb31a26, ghrun-e3snp7fbzi.auto.internal:2891): request = { session_id: "ydb://session/3?node_id=50000&id=NTI4ODY4ZjAtYjkzZDczM2UtZWE0ZTZiNmItMmZhYmQzMzg=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:18:32,090 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DropTable, 9809e9e8-2b59-4cca-95cc-8e7a3cb31a26, ghrun-e3snp7fbzi.auto.internal:2891): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:32,091 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:32,091 - DEBUG - ydb.table.SessionPool.2824334  - stop: Requested session pool stop.
2025-12-04 14:18:32,091 - DEBUG - ydb.table.SessionPool.2824334  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:18:32,091 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroying sessions in active queue
2025-12-04 14:18:32,092 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2886df31c0>, reason: session-pool-terminated
2025-12-04 14:18:32,092 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Session <ydb.table.Session object at 0x7f2886df31c0> is no longer active. Current active count 0.
2025-12-04 14:18:32,092 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DeleteSession, da0722a8-40a5-44c6-9f0f-315c916431a9, ghrun-e3snp7fbzi.auto.internal:2891): creating call state
2025-12-04 14:18:32,093 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DeleteSession, da0722a8-40a5-44c6-9f0f-315c916431a9, ghrun-e3snp7fbzi.auto.internal:2891): request = { session_id: "ydb://session/3?node_id=50000&id=NTI4ODY4ZjAtYjkzZDczM2UtZWE0ZTZiNmItMmZhYmQzMzg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:32,093 - DEBUG - ydb.table.SessionPool.2824334  - _terminate_event: Terminated session pool.
2025-12-04 14:18:32,095 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2886df31c0>
2025-12-04 14:18:32,095 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, da0722a8-40a5-44c6-9f0f-315c916431a9, ghrun-e3snp7fbzi.auto.internal:2891): on response callback started
2025-12-04 14:18:32,104 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DeleteSession, da0722a8-40a5-44c6-9f0f-315c916431a9, ghrun-e3snp7fbzi.auto.internal:2891): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:32,099 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroyed active sessions
2025-12-04 14:18:32,106 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, da0722a8-40a5-44c6-9f0f-315c916431a9, ghrun-e3snp7fbzi.auto.internal:2891): on response callback success
2025-12-04 14:18:32,111 - DEBUG - ydb.topic.2824334  - close: Close topic client
2025-12-04 14:18:32,112 - DEBUG - ydb.topic.2824334  - close: Topic client was closed
2025-12-04 14:18:32,113 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:32,114 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:2891
2025-12-04 14:18:32,115 - INFO - ydb.pool.Discovery.2824334  - run: Successfully terminated discovery process
2025-12-04 14:18:32,116 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 14:18:32,116 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - remove_database: /Root/users/database
2025-12-04 14:18:32,117 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 14:18:32,122 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764857912119749&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:18:32,123 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 14:18:32,631 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 14:18:32,632 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 14:18:32,635 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 14:18:35,639 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - stop: Stopped node localhost:2891/1
2025-12-04 14:18:35,639 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: done 0
2025-12-04 14:18:35,640 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 14:18:36,172 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:36,172 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32729
2025-12-04 14:18:36,173 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24217
2025-12-04 14:18:36,173 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17215
2025-12-04 14:18:36,173 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24967
2025-12-04 14:18:36,174 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15256
2025-12-04 14:18:36,175 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:36,176 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_5lthi64f.log --grpc-port=32729 --mon-port=24217 --ic-port=17215
2025-12-04 14:18:36,176 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:36,176 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_5lthi64f.log --grpc-port=32729 --mon-port=24217 --ic-port=17215
2025-12-04 14:18:36,177 - DEBUG - ya.test.2824334  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:8705', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_5lthi64f.log', '--grpc-port=32729', '--mon-port=24217', '--ic-port=17215')' in '/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-12-04 14:18:36,188 - DEBUG - ya.test.2824334  - execute: Command pid: 2955694
2025-12-04 14:18:36,190 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Started node localhost:32729/2
2025-12-04 14:18:36,200 - DEBUG - ya.test.2824334  - _get_command_output_file: Command out will be placed to ydbd.out.41
2025-12-04 14:18:36,201 - DEBUG - ya.test.2824334  - _get_command_output_file: Command err will be placed to ydbd.err.41
2025-12-04 14:18:36,202 - DEBUG - ya.test.2824334  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:18:36,209 - DEBUG - ya.test.2824334  - execute: Command pid: 2955732
2025-12-04 14:18:36,839 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) rc: 0
2025-12-04 14:18:36,840 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) elapsed time (sec): 0.6287977695465088
2025-12-04 14:18:36,840 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) idrss: 0
2025-12-04 14:18:36,840 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) inblock: 0
2025-12-04 14:18:36,840 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) isrss: 0
2025-12-04 14:18:36,840 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) ixrss: 0
2025-12-04 14:18:36,841 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) majflt: 0
2025-12-04 14:18:36,841 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) maxrss: 546756
2025-12-04 14:18:36,841 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) minflt: 16259
2025-12-04 14:18:36,841 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) msgrcv: 0
2025-12-04 14:18:36,841 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) msgsnd: 0
2025-12-04 14:18:36,842 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) nivcsw: 112
2025-12-04 14:18:36,842 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) nsignals: 0
2025-12-04 14:18:36,842 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) nswap: 0
2025-12-04 14:18:36,842 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) nvcsw: 18
2025-12-04 14:18:36,852 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) oublock: 8
2025-12-04 14:18:36,855 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) stime: 0.237865
2025-12-04 14:18:36,855 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) utime: 0.130442
2025-12-04 14:18:36,855 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) wtime: 0.637
2025-12-04 14:18:36,856 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 14:18:36,857 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2955732) errors:

2025-12-04 14:18:36,857 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Node 2 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:18:36,858 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:36,863 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:36,865 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 14:18:36,889 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:18:36,875 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:36,894 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Subscribe waiter <Future at 0x7f2886dc3e40 state=pending>
2025-12-04 14:18:36,899 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 14:18:36,899 - DEBUG - ydb.table.SessionPool.2824334  - _create: Created session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:36,899 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:36,900 - ERROR - ydb.table.SessionPool.2824334  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:18:36,914 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:36,918 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, f096b6cc-d52b-4ece-a5ff-2ca067c6e9e4, localhost:8705): creating call state
2025-12-04 14:18:36,918 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, f096b6cc-d52b-4ece-a5ff-2ca067c6e9e4, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:36,928 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, f096b6cc-d52b-4ece-a5ff-2ca067c6e9e4, localhost:8705): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-04 14:18:36,929 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:8705". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-04 14:18:36,929 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:38,826 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:38,827 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:38,850 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:38,851 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, ac131ceb-d8cc-4354-877e-c7deb6079831, localhost:8705): creating call state
2025-12-04 14:18:38,851 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, ac131ceb-d8cc-4354-877e-c7deb6079831, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:38,855 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, ac131ceb-d8cc-4354-877e-c7deb6079831, localhost:8705): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 32729 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50001 } self_location: "1" } } } }
2025-12-04 14:18:38,856 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:32729, location , ssl: False>]>
2025-12-04 14:18:38,857 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:38,871 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:38,872 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:38,875 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateSession, bdf19b4d-802c-4994-9982-ff909ce03bc2, ghrun-e3snp7fbzi.auto.internal:32729): creating call state
2025-12-04 14:18:38,876 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateSession, bdf19b4d-802c-4994-9982-ff909ce03bc2, ghrun-e3snp7fbzi.auto.internal:32729): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:38,883 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, bdf19b4d-802c-4994-9982-ff909ce03bc2, ghrun-e3snp7fbzi.auto.internal:32729): on response callback started
2025-12-04 14:18:38,884 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateSession, bdf19b4d-802c-4994-9982-ff909ce03bc2, ghrun-e3snp7fbzi.auto.internal:32729): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=MjRiNTkyOS01ZjBjN2FiOS0zMTFjYjQyYy1mYjkyMzM2" } } } }
2025-12-04 14:18:38,885 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:38,885 - DEBUG - ydb.table.SessionPool.2824334  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:38,885 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, bdf19b4d-802c-4994-9982-ff909ce03bc2, ghrun-e3snp7fbzi.auto.internal:32729): on response callback success
2025-12-04 14:18:38,886 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, 4fb57704-b096-4d7b-9223-0009f8405478, ghrun-e3snp7fbzi.auto.internal:32729): creating call state
2025-12-04 14:18:38,887 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, 4fb57704-b096-4d7b-9223-0009f8405478, ghrun-e3snp7fbzi.auto.internal:32729): request = { session_id: "ydb://session/3?node_id=50001&id=MjRiNTkyOS01ZjBjN2FiOS0zMTFjYjQyYy1mYjkyMzM2" 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-04 14:18:38,959 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, 4fb57704-b096-4d7b-9223-0009f8405478, ghrun-e3snp7fbzi.auto.internal:32729): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:38,975 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:38,976 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 14:18:38,979 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, a1d37c4f-629c-45a2-b0c6-51222b89a4e6, ghrun-e3snp7fbzi.auto.internal:32729): creating call state
2025-12-04 14:18:38,980 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, a1d37c4f-629c-45a2-b0c6-51222b89a4e6, ghrun-e3snp7fbzi.auto.internal:32729): request = { session_id: "ydb://session/3?node_id=50001&id=MjRiNTkyOS01ZjBjN2FiOS0zMTFjYjQyYy1mYjkyMzM2" 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-04 14:18:39,048 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, a1d37c4f-629c-45a2-b0c6-51222b89a4e6, ghrun-e3snp7fbzi.auto.internal:32729): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:39,048 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:39,051 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 14:18:39,065 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, 79f9e362-e571-46fa-917a-e8a3d93d73f7, ghrun-e3snp7fbzi.auto.internal:32729): creating call state
2025-12-04 14:18:39,066 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, 79f9e362-e571-46fa-917a-e8a3d93d73f7, ghrun-e3snp7fbzi.auto.internal:32729): request = { session_id: "ydb://session/3?node_id=50001&id=MjRiNTkyOS01ZjBjN2FiOS0zMTFjYjQyYy1mYjkyMzM2" 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-04 14:18:39,114 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, 79f9e362-e571-46fa-917a-e8a3d93d73f7, ghrun-e3snp7fbzi.auto.internal:32729): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 14:18:39,143 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:39,145 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 14:18:39,145 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, 033cf4c9-c158-43ba-be23-0aeeab64614f, ghrun-e3snp7fbzi.auto.internal:32729): creating call state
2025-12-04 14:18:39,146 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, 033cf4c9-c158-43ba-be23-0aeeab64614f, ghrun-e3snp7fbzi.auto.internal:32729): request = { session_id: "ydb://session/3?node_id=50001&id=MjRiNTkyOS01ZjBjN2FiOS0zMTFjYjQyYy1mYjkyMzM2" 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-04 14:18:39,202 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, 033cf4c9-c158-43ba-be23-0aeeab64614f, ghrun-e3snp7fbzi.auto.internal:32729): 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-04 14:18:39,222 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:39,231 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 14:18:39,233 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DropTable, c040a224-ea2d-4ed2-a753-bd9af2ecb776, ghrun-e3snp7fbzi.auto.internal:32729): creating call state
2025-12-04 14:18:39,234 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DropTable, c040a224-ea2d-4ed2-a753-bd9af2ecb776, ghrun-e3snp7fbzi.auto.internal:32729): request = { session_id: "ydb://session/3?node_id=50001&id=MjRiNTkyOS01ZjBjN2FiOS0zMTFjYjQyYy1mYjkyMzM2" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:18:39,289 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DropTable, c040a224-ea2d-4ed2-a753-bd9af2ecb776, ghrun-e3snp7fbzi.auto.internal:32729): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:39,289 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:39,289 - DEBUG - ydb.table.SessionPool.2824334  - stop: Requested session pool stop.
2025-12-04 14:18:39,292 - DEBUG - ydb.table.SessionPool.2824334  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:18:39,292 - DEBUG - ydb.table.SessionPool.2824334  - _terminate_event: Terminated session pool.
2025-12-04 14:18:39,293 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroying sessions in active queue
2025-12-04 14:18:39,294 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f2886dc31c0>, reason: session-pool-terminated
2025-12-04 14:18:39,294 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Session <ydb.table.Session object at 0x7f2886dc31c0> is no longer active. Current active count 0.
2025-12-04 14:18:39,294 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DeleteSession, ec4684ed-3a4e-4c38-bb66-7172cf84b99a, ghrun-e3snp7fbzi.auto.internal:32729): creating call state
2025-12-04 14:18:39,295 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DeleteSession, ec4684ed-3a4e-4c38-bb66-7172cf84b99a, ghrun-e3snp7fbzi.auto.internal:32729): request = { session_id: "ydb://session/3?node_id=50001&id=MjRiNTkyOS01ZjBjN2FiOS0zMTFjYjQyYy1mYjkyMzM2" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:39,303 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, ec4684ed-3a4e-4c38-bb66-7172cf84b99a, ghrun-e3snp7fbzi.auto.internal:32729): on response callback started
2025-12-04 14:18:39,304 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DeleteSession, ec4684ed-3a4e-4c38-bb66-7172cf84b99a, ghrun-e3snp7fbzi.auto.internal:32729): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:39,304 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, ec4684ed-3a4e-4c38-bb66-7172cf84b99a, ghrun-e3snp7fbzi.auto.internal:32729): on response callback success
2025-12-04 14:18:39,311 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f2886dc31c0>
2025-12-04 14:18:39,311 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroyed active sessions
2025-12-04 14:18:39,311 - DEBUG - ydb.topic.2824334  - close: Close topic client
2025-12-04 14:18:39,315 - DEBUG - ydb.topic.2824334  - close: Topic client was closed
2025-12-04 14:18:39,315 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:39,316 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:32729
2025-12-04 14:18:39,317 - INFO - ydb.pool.Discovery.2824334  - run: Successfully terminated discovery process
2025-12-04 14:18:39,322 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 14:18:39,323 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - remove_database: /Root/users/database
2025-12-04 14:18:39,323 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 14:18:39,331 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764857919325761&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:18:39,347 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 14:18:39,855 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 14:18:39,855 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 14:18:39,859 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 14:18:42,863 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - stop: Stopped node localhost:32729/2
2025-12-04 14:18:42,864 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: done 1
2025-12-04 14:18:42,867 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 14:18:43,385 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:43,386 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61253
2025-12-04 14:18:43,387 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63950
2025-12-04 14:18:43,387 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/65048
2025-12-04 14:18:43,387 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4144
2025-12-04 14:18:43,388 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9953
2025-12-04 14:18:43,388 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:43,388 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_w29p1wh1.log --grpc-port=61253 --mon-port=63950 --ic-port=65048
2025-12-04 14:18:43,389 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:43,389 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_w29p1wh1.log --grpc-port=61253 --mon-port=63950 --ic-port=65048
2025-12-04 14:18:43,390 - DEBUG - ya.test.2824334  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:8705', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_w29p1wh1.log', '--grpc-port=61253', '--mon-port=63950', '--ic-port=65048')' in '/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-12-04 14:18:43,392 - DEBUG - ya.test.2824334  - execute: Command pid: 2959039
2025-12-04 14:18:43,396 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Started node localhost:61253/3
2025-12-04 14:18:43,398 - DEBUG - ya.test.2824334  - _get_command_output_file: Command out will be placed to ydbd.out.42
2025-12-04 14:18:43,399 - DEBUG - ya.test.2824334  - _get_command_output_file: Command err will be placed to ydbd.err.42
2025-12-04 14:18:43,399 - DEBUG - ya.test.2824334  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:18:43,400 - DEBUG - ya.test.2824334  - execute: Command pid: 2959049
2025-12-04 14:18:43,907 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) rc: 0
2025-12-04 14:18:43,908 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) elapsed time (sec): 0.5041012763977051
2025-12-04 14:18:43,908 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) idrss: 0
2025-12-04 14:18:43,908 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) inblock: 0
2025-12-04 14:18:43,908 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) isrss: 0
2025-12-04 14:18:43,909 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) ixrss: 0
2025-12-04 14:18:43,909 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) majflt: 0
2025-12-04 14:18:43,909 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) maxrss: 546756
2025-12-04 14:18:43,909 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) minflt: 16258
2025-12-04 14:18:43,909 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) msgrcv: 0
2025-12-04 14:18:43,909 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) msgsnd: 0
2025-12-04 14:18:43,910 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) nivcsw: 92
2025-12-04 14:18:43,910 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) nsignals: 0
2025-12-04 14:18:43,910 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) nswap: 0
2025-12-04 14:18:43,912 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) nvcsw: 17
2025-12-04 14:18:43,912 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) oublock: 8
2025-12-04 14:18:43,912 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) stime: 0.282202
2025-12-04 14:18:43,914 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) utime: 0.118224
2025-12-04 14:18:43,914 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) wtime: 0.506
2025-12-04 14:18:43,915 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 14:18:43,915 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2959049) errors:

2025-12-04 14:18:43,915 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Node 3 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:18:43,917 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:43,923 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:43,928 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:43,934 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 14:18:43,939 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:18:43,939 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Subscribe waiter <Future at 0x7f288ae7b260 state=pending>
2025-12-04 14:18:43,939 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 14:18:43,939 - DEBUG - ydb.table.SessionPool.2824334  - _create: Created session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:43,940 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:43,940 - ERROR - ydb.table.SessionPool.2824334  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:18:43,940 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:43,941 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, 3679f679-49e3-4bad-ba20-895a24585d89, localhost:8705): creating call state
2025-12-04 14:18:43,941 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, 3679f679-49e3-4bad-ba20-895a24585d89, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:43,944 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, 3679f679-49e3-4bad-ba20-895a24585d89, localhost:8705): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-04 14:18:43,961 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:8705". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-04 14:18:43,980 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:45,304 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:45,305 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:45,311 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:45,325 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, f852376d-3718-4631-ad9b-5ae00d13cf0f, localhost:8705): creating call state
2025-12-04 14:18:45,334 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, f852376d-3718-4631-ad9b-5ae00d13cf0f, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:45,340 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, f852376d-3718-4631-ad9b-5ae00d13cf0f, localhost:8705): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 61253 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50002 } self_location: "1" } } } }
2025-12-04 14:18:45,341 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:61253, location , ssl: False>]>
2025-12-04 14:18:45,341 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:45,352 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:45,352 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,353 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateSession, 9ff3d2f9-f1c3-4688-8a8e-34da6c222a40, ghrun-e3snp7fbzi.auto.internal:61253): creating call state
2025-12-04 14:18:45,353 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateSession, 9ff3d2f9-f1c3-4688-8a8e-34da6c222a40, ghrun-e3snp7fbzi.auto.internal:61253): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:45,362 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, 9ff3d2f9-f1c3-4688-8a8e-34da6c222a40, ghrun-e3snp7fbzi.auto.internal:61253): on response callback started
2025-12-04 14:18:45,363 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateSession, 9ff3d2f9-f1c3-4688-8a8e-34da6c222a40, ghrun-e3snp7fbzi.auto.internal:61253): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=YmNjOWNhMGYtMWNlZDZhZWYtZmMzODU3YmEtMjdkMzA3MDc=" } } } }
2025-12-04 14:18:45,363 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,363 - DEBUG - ydb.table.SessionPool.2824334  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,364 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, 9ff3d2f9-f1c3-4688-8a8e-34da6c222a40, ghrun-e3snp7fbzi.auto.internal:61253): on response callback success
2025-12-04 14:18:45,365 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, e420c67f-ff2c-452d-abc8-b266dcdb9fc8, ghrun-e3snp7fbzi.auto.internal:61253): creating call state
2025-12-04 14:18:45,365 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, e420c67f-ff2c-452d-abc8-b266dcdb9fc8, ghrun-e3snp7fbzi.auto.internal:61253): request = { session_id: "ydb://session/3?node_id=50002&id=YmNjOWNhMGYtMWNlZDZhZWYtZmMzODU3YmEtMjdkMzA3MDc=" 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-04 14:18:45,435 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, e420c67f-ff2c-452d-abc8-b266dcdb9fc8, ghrun-e3snp7fbzi.auto.internal:61253): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:45,436 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,436 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 14:18:45,437 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, e0c19832-645b-4a88-8244-b776c07827e8, ghrun-e3snp7fbzi.auto.internal:61253): creating call state
2025-12-04 14:18:45,437 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, e0c19832-645b-4a88-8244-b776c07827e8, ghrun-e3snp7fbzi.auto.internal:61253): request = { session_id: "ydb://session/3?node_id=50002&id=YmNjOWNhMGYtMWNlZDZhZWYtZmMzODU3YmEtMjdkMzA3MDc=" 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-04 14:18:45,483 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, e0c19832-645b-4a88-8244-b776c07827e8, ghrun-e3snp7fbzi.auto.internal:61253): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:45,483 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,484 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 14:18:45,484 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, 4838c706-2b5b-4b89-8ef4-93446084e4f3, ghrun-e3snp7fbzi.auto.internal:61253): creating call state
2025-12-04 14:18:45,485 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, 4838c706-2b5b-4b89-8ef4-93446084e4f3, ghrun-e3snp7fbzi.auto.internal:61253): request = { session_id: "ydb://session/3?node_id=50002&id=YmNjOWNhMGYtMWNlZDZhZWYtZmMzODU3YmEtMjdkMzA3MDc=" 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-04 14:18:45,534 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, 4838c706-2b5b-4b89-8ef4-93446084e4f3, ghrun-e3snp7fbzi.auto.internal:61253): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 14:18:45,539 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,539 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 14:18:45,540 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, 394628d4-a36d-4eae-a136-623c23c1450c, ghrun-e3snp7fbzi.auto.internal:61253): creating call state
2025-12-04 14:18:45,541 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, 394628d4-a36d-4eae-a136-623c23c1450c, ghrun-e3snp7fbzi.auto.internal:61253): request = { session_id: "ydb://session/3?node_id=50002&id=YmNjOWNhMGYtMWNlZDZhZWYtZmMzODU3YmEtMjdkMzA3MDc=" 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-04 14:18:45,587 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, 394628d4-a36d-4eae-a136-623c23c1450c, ghrun-e3snp7fbzi.auto.internal:61253): 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-04 14:18:45,588 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,588 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 14:18:45,590 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DropTable, 2e578702-74e3-490f-b5bc-28a3c1f356b1, ghrun-e3snp7fbzi.auto.internal:61253): creating call state
2025-12-04 14:18:45,590 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DropTable, 2e578702-74e3-490f-b5bc-28a3c1f356b1, ghrun-e3snp7fbzi.auto.internal:61253): request = { session_id: "ydb://session/3?node_id=50002&id=YmNjOWNhMGYtMWNlZDZhZWYtZmMzODU3YmEtMjdkMzA3MDc=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:18:45,618 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DropTable, 2e578702-74e3-490f-b5bc-28a3c1f356b1, ghrun-e3snp7fbzi.auto.internal:61253): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:45,619 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,619 - DEBUG - ydb.table.SessionPool.2824334  - stop: Requested session pool stop.
2025-12-04 14:18:45,619 - DEBUG - ydb.table.SessionPool.2824334  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:18:45,619 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroying sessions in active queue
2025-12-04 14:18:45,620 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f288ae7bda0>, reason: session-pool-terminated
2025-12-04 14:18:45,637 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Session <ydb.table.Session object at 0x7f288ae7bda0> is no longer active. Current active count 0.
2025-12-04 14:18:45,638 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DeleteSession, db0309a8-f49e-4bc0-b042-df6d934706c6, ghrun-e3snp7fbzi.auto.internal:61253): creating call state
2025-12-04 14:18:45,626 - DEBUG - ydb.table.SessionPool.2824334  - _terminate_event: Terminated session pool.
2025-12-04 14:18:45,638 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DeleteSession, db0309a8-f49e-4bc0-b042-df6d934706c6, ghrun-e3snp7fbzi.auto.internal:61253): request = { session_id: "ydb://session/3?node_id=50002&id=YmNjOWNhMGYtMWNlZDZhZWYtZmMzODU3YmEtMjdkMzA3MDc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:45,653 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f288ae7bda0>
2025-12-04 14:18:45,654 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroyed active sessions
2025-12-04 14:18:45,654 - DEBUG - ydb.topic.2824334  - close: Close topic client
2025-12-04 14:18:45,654 - DEBUG - ydb.topic.2824334  - close: Topic client was closed
2025-12-04 14:18:45,654 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:45,655 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:61253
2025-12-04 14:18:45,656 - INFO - ydb.pool.Discovery.2824334  - run: Successfully terminated discovery process
2025-12-04 14:18:45,655 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, db0309a8-f49e-4bc0-b042-df6d934706c6, ghrun-e3snp7fbzi.auto.internal:61253): on response callback started
2025-12-04 14:18:45,656 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DeleteSession, db0309a8-f49e-4bc0-b042-df6d934706c6, ghrun-e3snp7fbzi.auto.internal:61253): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:45,657 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, db0309a8-f49e-4bc0-b042-df6d934706c6, ghrun-e3snp7fbzi.auto.internal:61253): on response callback success
2025-12-04 14:18:45,658 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 14:18:45,658 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - remove_database: /Root/users/database
2025-12-04 14:18:45,658 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 14:18:45,664 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764857925660211&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:18:45,675 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 14:18:46,185 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 14:18:46,186 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 14:18:46,192 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 14:18:49,195 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - stop: Stopped node localhost:61253/3
2025-12-04 14:18:49,195 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: done 2
2025-12-04 14:18:49,195 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 14:18:49,720 - DEBUG - ya.test.2824334  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd
2025-12-04 14:18:49,724 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61932
2025-12-04 14:18:49,724 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14171
2025-12-04 14:18:49,725 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23235
2025-12-04 14:18:49,725 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63291
2025-12-04 14:18:49,725 - DEBUG - library.python.filelock.2824334  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2212
2025-12-04 14:18:49,726 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:49,731 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_k5h7kcu9.log --grpc-port=61932 --mon-port=14171 --ic-port=23235
2025-12-04 14:18:49,734 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:49,751 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8705 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_k5h7kcu9.log --grpc-port=61932 --mon-port=14171 --ic-port=23235
2025-12-04 14:18:49,753 - DEBUG - ya.test.2824334  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:8705', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_k5h7kcu9.log', '--grpc-port=61932', '--mon-port=14171', '--ic-port=23235')' in '/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4' (no wait)
2025-12-04 14:18:49,760 - DEBUG - ya.test.2824334  - execute: Command pid: 2960818
2025-12-04 14:18:49,777 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Started node localhost:61932/4
2025-12-04 14:18:49,779 - DEBUG - ya.test.2824334  - _get_command_output_file: Command out will be placed to ydbd.out.43
2025-12-04 14:18:49,780 - DEBUG - ya.test.2824334  - _get_command_output_file: Command err will be placed to ydbd.err.43
2025-12-04 14:18:49,781 - DEBUG - ya.test.2824334  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/0027aa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:18:49,791 - DEBUG - ya.test.2824334  - execute: Command pid: 2960843
2025-12-04 14:18:50,497 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) rc: 0
2025-12-04 14:18:50,498 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) elapsed time (sec): 0.702911376953125
2025-12-04 14:18:50,503 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) idrss: 0
2025-12-04 14:18:50,504 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) inblock: 0
2025-12-04 14:18:50,504 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) isrss: 0
2025-12-04 14:18:50,504 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) ixrss: 0
2025-12-04 14:18:50,505 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) majflt: 0
2025-12-04 14:18:50,506 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) maxrss: 546756
2025-12-04 14:18:50,506 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) minflt: 16260
2025-12-04 14:18:50,506 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) msgrcv: 0
2025-12-04 14:18:50,506 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) msgsnd: 0
2025-12-04 14:18:50,506 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) nivcsw: 200
2025-12-04 14:18:50,511 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) nsignals: 0
2025-12-04 14:18:50,526 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) nswap: 0
2025-12-04 14:18:50,526 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) nvcsw: 15
2025-12-04 14:18:50,527 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) oublock: 8
2025-12-04 14:18:50,536 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) stime: 0.33415
2025-12-04 14:18:50,542 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) utime: 0.132009
2025-12-04 14:18:50,544 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) wtime: 0.716
2025-12-04 14:18:50,544 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 14:18:50,544 - DEBUG - ya.test.2824334  - _save_outputs: Command (pid 2960843) errors:

2025-12-04 14:18:50,544 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - start: Node 4 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:18:50,545 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:50,547 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:50,547 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:50,569 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 14:18:50,570 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:18:50,570 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Subscribe waiter <Future at 0x7f288a5e4920 state=pending>
2025-12-04 14:18:50,570 - DEBUG - ydb.table.SessionPool.2824334  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 14:18:50,570 - DEBUG - ydb.table.SessionPool.2824334  - _create: Created session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:50,571 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:50,571 - ERROR - ydb.table.SessionPool.2824334  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:18:50,614 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:50,615 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, 04b46a33-e832-49f9-8bfe-44089f8e0ce9, localhost:8705): creating call state
2025-12-04 14:18:50,616 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, 04b46a33-e832-49f9-8bfe-44089f8e0ce9, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:50,619 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, 04b46a33-e832-49f9-8bfe-44089f8e0ce9, localhost:8705): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-04 14:18:50,619 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:8705". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-04 14:18:50,619 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:51,970 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:51,971 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:52,006 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 14:18:52,007 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ListEndpoints, 9bd5a642-3866-46fa-a701-8683f82b84a1, localhost:8705): creating call state
2025-12-04 14:18:52,007 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ListEndpoints, 9bd5a642-3866-46fa-a701-8683f82b84a1, localhost:8705): request = { database: "/Root/users/database" }
2025-12-04 14:18:52,010 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ListEndpoints, 9bd5a642-3866-46fa-a701-8683f82b84a1, localhost:8705): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 61932 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-04 14:18:52,011 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2824334  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:61932, location , ssl: False>]>
2025-12-04 14:18:52,011 - DEBUG - ydb.connection.2824334  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 14:18:52,030 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:52,030 - DEBUG - ydb.table.SessionPool.2824334  - _prepare: Preparing session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,052 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateSession, 18abd2c8-604b-4d5b-81e8-ada9deb2d081, ghrun-e3snp7fbzi.auto.internal:61932): creating call state
2025-12-04 14:18:52,053 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateSession, 18abd2c8-604b-4d5b-81e8-ada9deb2d081, ghrun-e3snp7fbzi.auto.internal:61932): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:52,059 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, 18abd2c8-604b-4d5b-81e8-ada9deb2d081, ghrun-e3snp7fbzi.auto.internal:61932): on response callback started
2025-12-04 14:18:52,060 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateSession, 18abd2c8-604b-4d5b-81e8-ada9deb2d081, ghrun-e3snp7fbzi.auto.internal:61932): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=MjY4YmZkOWMtNGRiNjY1YmQtZjlkZDMwNmUtOTFmNGRhZmQ=" } } } }
2025-12-04 14:18:52,060 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,060 - DEBUG - ydb.table.SessionPool.2824334  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,061 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, 7e58af22-1741-4ddb-acd6-5f235a9eed36, ghrun-e3snp7fbzi.auto.internal:61932): creating call state
2025-12-04 14:18:52,062 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(CreateSession, 18abd2c8-604b-4d5b-81e8-ada9deb2d081, ghrun-e3snp7fbzi.auto.internal:61932): on response callback success
2025-12-04 14:18:52,063 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, 7e58af22-1741-4ddb-acd6-5f235a9eed36, ghrun-e3snp7fbzi.auto.internal:61932): request = { session_id: "ydb://session/3?node_id=50003&id=MjY4YmZkOWMtNGRiNjY1YmQtZjlkZDMwNmUtOTFmNGRhZmQ=" 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-04 14:18:52,171 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, 7e58af22-1741-4ddb-acd6-5f235a9eed36, ghrun-e3snp7fbzi.auto.internal:61932): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:52,182 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,182 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 14:18:52,183 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(CreateTable, 997a9dee-8793-4c7e-add0-8b2ec5b488ea, ghrun-e3snp7fbzi.auto.internal:61932): creating call state
2025-12-04 14:18:52,184 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(CreateTable, 997a9dee-8793-4c7e-add0-8b2ec5b488ea, ghrun-e3snp7fbzi.auto.internal:61932): request = { session_id: "ydb://session/3?node_id=50003&id=MjY4YmZkOWMtNGRiNjY1YmQtZjlkZDMwNmUtOTFmNGRhZmQ=" 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-04 14:18:52,253 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(CreateTable, 997a9dee-8793-4c7e-add0-8b2ec5b488ea, ghrun-e3snp7fbzi.auto.internal:61932): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:52,277 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,277 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 14:18:52,278 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, 00500e02-db81-42ad-9257-34f8c8fccb73, ghrun-e3snp7fbzi.auto.internal:61932): creating call state
2025-12-04 14:18:52,278 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, 00500e02-db81-42ad-9257-34f8c8fccb73, ghrun-e3snp7fbzi.auto.internal:61932): request = { session_id: "ydb://session/3?node_id=50003&id=MjY4YmZkOWMtNGRiNjY1YmQtZjlkZDMwNmUtOTFmNGRhZmQ=" 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-04 14:18:52,336 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, 00500e02-db81-42ad-9257-34f8c8fccb73, ghrun-e3snp7fbzi.auto.internal:61932): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 14:18:52,337 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,337 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 14:18:52,338 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(ExecuteDataQuery, bdd4db68-e12b-42e3-affd-77db2d06e9fb, ghrun-e3snp7fbzi.auto.internal:61932): creating call state
2025-12-04 14:18:52,338 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(ExecuteDataQuery, bdd4db68-e12b-42e3-affd-77db2d06e9fb, ghrun-e3snp7fbzi.auto.internal:61932): request = { session_id: "ydb://session/3?node_id=50003&id=MjY4YmZkOWMtNGRiNjY1YmQtZjlkZDMwNmUtOTFmNGRhZmQ=" 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-04 14:18:52,386 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(ExecuteDataQuery, bdd4db68-e12b-42e3-affd-77db2d06e9fb, ghrun-e3snp7fbzi.auto.internal:61932): 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-04 14:18:52,387 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,387 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 14:18:52,388 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DropTable, 102882db-1391-4d23-81c8-57f3979fe4c2, ghrun-e3snp7fbzi.auto.internal:61932): creating call state
2025-12-04 14:18:52,388 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DropTable, 102882db-1391-4d23-81c8-57f3979fe4c2, ghrun-e3snp7fbzi.auto.internal:61932): request = { session_id: "ydb://session/3?node_id=50003&id=MjY4YmZkOWMtNGRiNjY1YmQtZjlkZDMwNmUtOTFmNGRhZmQ=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:18:52,414 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DropTable, 102882db-1391-4d23-81c8-57f3979fe4c2, ghrun-e3snp7fbzi.auto.internal:61932): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:52,459 - DEBUG - ydb.table.SessionPool.2824334  - put: Put on session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,459 - DEBUG - ydb.table.SessionPool.2824334  - stop: Requested session pool stop.
2025-12-04 14:18:52,460 - DEBUG - ydb.table.SessionPool.2824334  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:18:52,460 - DEBUG - ydb.table.SessionPool.2824334  - _terminate_event: Terminated session pool.
2025-12-04 14:18:52,463 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroying sessions in active queue
2025-12-04 14:18:52,467 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f288a5e5af0>, reason: session-pool-terminated
2025-12-04 14:18:52,467 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Session <ydb.table.Session object at 0x7f288a5e5af0> is no longer active. Current active count 0.
2025-12-04 14:18:52,467 - DEBUG - ydb.connection.2824334  - _prepare_call: RpcState(DeleteSession, fd940009-181b-44ae-87ec-ab929eec58da, ghrun-e3snp7fbzi.auto.internal:61932): creating call state
2025-12-04 14:18:52,468 - DEBUG - ydb.connection.2824334  - _log_request: RpcState(DeleteSession, fd940009-181b-44ae-87ec-ab929eec58da, ghrun-e3snp7fbzi.auto.internal:61932): request = { session_id: "ydb://session/3?node_id=50003&id=MjY4YmZkOWMtNGRiNjY1YmQtZjlkZDMwNmUtOTFmNGRhZmQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:52,470 - DEBUG - ydb.table.SessionPool.2824334  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f288a5e5af0>
2025-12-04 14:18:52,470 - DEBUG - ydb.table.SessionPool.2824334  - stop: Destroyed active sessions
2025-12-04 14:18:52,470 - DEBUG - ydb.topic.2824334  - close: Close topic client
2025-12-04 14:18:52,470 - DEBUG - ydb.topic.2824334  - close: Topic client was closed
2025-12-04 14:18:52,471 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint localhost:8705
2025-12-04 14:18:52,471 - DEBUG - ydb.connection.2824334  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:61932
2025-12-04 14:18:52,471 - INFO - ydb.pool.Discovery.2824334  - run: Successfully terminated discovery process
2025-12-04 14:18:52,472 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 14:18:52,472 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - remove_database: /Root/users/database
2025-12-04 14:18:52,472 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 14:18:52,473 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, fd940009-181b-44ae-87ec-ab929eec58da, ghrun-e3snp7fbzi.auto.internal:61932): on response callback started
2025-12-04 14:18:52,474 - DEBUG - ydb.connection.2824334  - _log_response: RpcState(DeleteSession, fd940009-181b-44ae-87ec-ab929eec58da, ghrun-e3snp7fbzi.auto.internal:61932): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:52,474 - DEBUG - ydb.connection.2824334  - _on_response_callback: RpcState(DeleteSession, fd940009-181b-44ae-87ec-ab929eec58da, ghrun-e3snp7fbzi.auto.internal:61932): on response callback success
2025-12-04 14:18:52,479 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764857932474973&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:18:52,483 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 14:18:52,990 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 14:18:52,991 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 14:18:52,994 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2824334  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 14:18:55,995 - INFO - ydb.tests.library.harness.kikimr_runner.2824334  - stop: Stopped node localhost:61932/4
2025-12-04 14:18:56,003 - DEBUG - __tests__.test_dynamic_tenants.2824334  - test_create_and_drop_the_same_tenant2: done 3
2025-12-04 14:18:56,008 - INFO - ya.test.2824334  - pytest_runtest_teardown: Test teardown
