2025-12-07 20:07:56,775 - INFO - ya.test.1714109  - pytest_runtest_setup: ####################################################################################################
2025-12-07 20:07:56,775 - INFO - ya.test.1714109  - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--false]
2025-12-07 20:07:56,775 - INFO - ya.test.1714109  - pytest_runtest_setup: ####################################################################################################
2025-12-07 20:07:56,775 - INFO - ya.test.1714109  - pytest_runtest_setup: Test setup
2025-12-07 20:07:56,776 - INFO - ydb.tests.library.fixtures.1714109  - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-12-07 20:07:59,777 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - stop: Stopped node localhost:4763/1
2025-12-07 20:07:59,778 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4613
2025-12-07 20:07:59,778 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9269
2025-12-07 20:07:59,778 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4586
2025-12-07 20:07:59,779 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27330
2025-12-07 20:07:59,779 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1349
2025-12-07 20:07:59,779 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17776
2025-12-07 20:07:59,779 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12838
2025-12-07 20:07:59,779 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29196
2025-12-07 20:07:59,779 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1814
2025-12-07 20:07:59,780 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25446
2025-12-07 20:07:59,780 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10806
2025-12-07 20:07:59,780 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24724
2025-12-07 20:07:59,780 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/65104
2025-12-07 20:07:59,780 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24478
2025-12-07 20:07:59,780 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25355
2025-12-07 20:07:59,780 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24443
2025-12-07 20:07:59,781 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2855
2025-12-07 20:07:59,781 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15444
2025-12-07 20:07:59,781 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27131
2025-12-07 20:07:59,781 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2518
2025-12-07 20:07:59,781 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28274
2025-12-07 20:07:59,781 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8376
2025-12-07 20:07:59,782 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61502
2025-12-07 20:07:59,782 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63248
2025-12-07 20:07:59,782 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13856
2025-12-07 20:07:59,782 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22477
2025-12-07 20:07:59,782 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30406
2025-12-07 20:07:59,783 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28969
2025-12-07 20:07:59,783 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25357
2025-12-07 20:07:59,783 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63021
2025-12-07 20:07:59,783 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/65046
2025-12-07 20:07:59,783 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19153
2025-12-07 20:07:59,783 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7355
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14790
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11855
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20315
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7659
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63692
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8079
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25932
2025-12-07 20:07:59,784 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11974
2025-12-07 20:07:59,785 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2930
2025-12-07 20:07:59,785 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15841
2025-12-07 20:07:59,785 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15624
2025-12-07 20:07:59,785 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22852
2025-12-07 20:07:59,785 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18293
2025-12-07 20:07:59,785 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18045
2025-12-07 20:07:59,786 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27332
2025-12-07 20:07:59,786 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11682
2025-12-07 20:07:59,786 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12501
2025-12-07 20:07:59,786 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4822
2025-12-07 20:07:59,787 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15184
2025-12-07 20:07:59,787 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29700
2025-12-07 20:07:59,787 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4763
2025-12-07 20:07:59,787 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11514
2025-12-07 20:07:59,852 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:07:59,853 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11957
2025-12-07 20:07:59,854 - INFO - ydb.tests.library.fixtures.1714109  - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-12-07 20:07:59,854 - INFO - ydb.tests.library.fixtures.1714109  - ydb_cluster: setup ydb_cluster as local
2025-12-07 20:07:59,854 - DEBUG - ydb.tests.library.harness.kikimr_runner.1714109  - start: Working directory: /home/runner/.ya/build/build_root/8xn2/004476/r3tmp/kikimr_cluster_m1w41jj2
2025-12-07 20:07:59,895 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:07:59,896 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29188
2025-12-07 20:07:59,896 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27820
2025-12-07 20:07:59,896 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1800
2025-12-07 20:07:59,897 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20415
2025-12-07 20:07:59,897 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:07:59,897 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/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/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/node_1/logfile_t_gz_zx1.log --grpc-port=29188 --mon-port=27820 --ic-port=11957
2025-12-07 20:07:59,898 - DEBUG - ydb.tests.library.harness.kikimr_runner.1714109  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:29188/1, disk_size 68719476736
2025-12-07 20:07:59,898 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:07:59,898 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/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/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/node_1/logfile_t_gz_zx1.log --grpc-port=29188 --mon-port=27820 --ic-port=11957
2025-12-07 20:07:59,899 - DEBUG - ya.test.1714109  - execute: Executing '('/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/node_1/logfile_t_gz_zx1.log', '--grpc-port=29188', '--mon-port=27820', '--ic-port=11957')' in '/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/node_1' (no wait)
2025-12-07 20:07:59,900 - DEBUG - ya.test.1714109  - execute: Command pid: 2050775
2025-12-07 20:07:59,901 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Started node localhost:29188/1
2025-12-07 20:07:59,902 - DEBUG - ya.test.1714109  - _get_command_output_file: Command out will be placed to ydbd.out.30
2025-12-07 20:07:59,902 - DEBUG - ya.test.1714109  - _get_command_output_file: Command err will be placed to ydbd.err.30
2025-12-07 20:07:59,903 - DEBUG - ya.test.1714109  - execute: Executing '['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-07 20:07:59,903 - DEBUG - ya.test.1714109  - execute: Command pid: 2050776
2025-12-07 20:08:00,366 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) rc: 0
2025-12-07 20:08:00,366 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) elapsed time (sec): 0.46149492263793945
2025-12-07 20:08:00,366 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) idrss: 0
2025-12-07 20:08:00,366 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) inblock: 0
2025-12-07 20:08:00,367 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) isrss: 0
2025-12-07 20:08:00,367 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) ixrss: 0
2025-12-07 20:08:00,367 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) majflt: 0
2025-12-07 20:08:00,367 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) maxrss: 547172
2025-12-07 20:08:00,367 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) minflt: 16267
2025-12-07 20:08:00,368 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) msgrcv: 0
2025-12-07 20:08:00,368 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) msgsnd: 0
2025-12-07 20:08:00,368 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) nivcsw: 35
2025-12-07 20:08:00,368 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) nsignals: 0
2025-12-07 20:08:00,368 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) nswap: 0
2025-12-07 20:08:00,369 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) nvcsw: 13
2025-12-07 20:08:00,369 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) oublock: 8
2025-12-07 20:08:00,369 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) stime: 0.267971
2025-12-07 20:08:00,369 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) utime: 0.13399
2025-12-07 20:08:00,369 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) wtime: 0.463
2025-12-07 20:08:00,370 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) output:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:08:00,370 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2050776) errors:

2025-12-07 20:08:00,370 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Node 1 version:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-07 20:08:02,183 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:08:02,183 - DEBUG - ydb.tests.library.harness.kikimr_runner.1714109  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29188', '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: 11957\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-07 20:08:02,184 - DEBUG - ya.test.1714109  - _get_command_output_file: Command out will be placed to ydbd.out.31
2025-12-07 20:08:02,185 - DEBUG - ya.test.1714109  - _get_command_output_file: Command err will be placed to ydbd.err.31
2025-12-07 20:08:02,185 - DEBUG - ya.test.1714109  - execute: Executing '['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29188', '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: 11957\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-07 20:08:02,186 - DEBUG - ya.test.1714109  - execute: Command pid: 2054575
2025-12-07 20:08:02,686 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) rc: 0
2025-12-07 20:08:02,686 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) elapsed time (sec): 0.4986550807952881
2025-12-07 20:08:02,686 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) idrss: 0
2025-12-07 20:08:02,686 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) inblock: 0
2025-12-07 20:08:02,687 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) isrss: 0
2025-12-07 20:08:02,687 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) ixrss: 0
2025-12-07 20:08:02,687 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) majflt: 0
2025-12-07 20:08:02,687 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) maxrss: 555640
2025-12-07 20:08:02,687 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) minflt: 18409
2025-12-07 20:08:02,687 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) msgrcv: 0
2025-12-07 20:08:02,687 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) msgsnd: 0
2025-12-07 20:08:02,688 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) nivcsw: 8
2025-12-07 20:08:02,688 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) nsignals: 0
2025-12-07 20:08:02,688 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) nswap: 0
2025-12-07 20:08:02,688 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) nvcsw: 125
2025-12-07 20:08:02,688 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) oublock: 8
2025-12-07 20:08:02,688 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) stime: 0.294362
2025-12-07 20:08:02,689 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) utime: 0.15306799999999998
2025-12-07 20:08:02,689 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) wtime: 0.5
2025-12-07 20:08:02,689 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-07 20:08:02,689 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2054575) errors:

2025-12-07 20:08:02,690 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:08:02,690 - DEBUG - ydb.tests.library.harness.kikimr_runner.1714109  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29188', '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-07 20:08:02,692 - DEBUG - ya.test.1714109  - _get_command_output_file: Command out will be placed to ydbd.out.32
2025-12-07 20:08:02,693 - DEBUG - ya.test.1714109  - _get_command_output_file: Command err will be placed to ydbd.err.32
2025-12-07 20:08:02,698 - DEBUG - ya.test.1714109  - execute: Executing '['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29188', '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-07 20:08:02,699 - DEBUG - ya.test.1714109  - execute: Command pid: 2055333
2025-12-07 20:08:03,081 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) rc: 0
2025-12-07 20:08:03,081 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) elapsed time (sec): 0.3804633617401123
2025-12-07 20:08:03,081 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) idrss: 0
2025-12-07 20:08:03,081 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) inblock: 0
2025-12-07 20:08:03,082 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) isrss: 0
2025-12-07 20:08:03,082 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) ixrss: 0
2025-12-07 20:08:03,082 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) majflt: 0
2025-12-07 20:08:03,082 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) maxrss: 555652
2025-12-07 20:08:03,082 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) minflt: 18413
2025-12-07 20:08:03,083 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) msgrcv: 0
2025-12-07 20:08:03,083 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) msgsnd: 0
2025-12-07 20:08:03,083 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) nivcsw: 12
2025-12-07 20:08:03,083 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) nsignals: 0
2025-12-07 20:08:03,083 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) nswap: 0
2025-12-07 20:08:03,083 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) nvcsw: 145
2025-12-07 20:08:03,083 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) oublock: 8
2025-12-07 20:08:03,084 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) stime: 0.21004799999999998
2025-12-07 20:08:03,084 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) utime: 0.126821
2025-12-07 20:08:03,084 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) wtime: 0.382
2025-12-07 20:08:03,084 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-07 20:08:03,084 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2055333) errors:

2025-12-07 20:08:03,085 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-07 20:08:03,098 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __run: Cluster started and initialized
2025-12-07 20:08:03,109 - INFO - ya.test.1714109  - pytest_runtest_call: Test call (class_name: test_dynamic_tenants.py, test_name: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--false])
2025-12-07 20:08:03,110 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create_database
2025-12-07 20:08:03,622 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:08:03,623 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20483
2025-12-07 20:08:03,623 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23341
2025-12-07 20:08:03,624 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30511
2025-12-07 20:08:03,624 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17119
2025-12-07 20:08:03,624 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26447
2025-12-07 20:08:03,625 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:03,625 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_1/logfile_dx3rt0ib.log --grpc-port=20483 --mon-port=23341 --ic-port=30511
2025-12-07 20:08:03,626 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:03,626 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_1/logfile_dx3rt0ib.log --grpc-port=20483 --mon-port=23341 --ic-port=30511
2025-12-07 20:08:03,627 - DEBUG - ya.test.1714109  - execute: Executing '('/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29188', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_1/logfile_dx3rt0ib.log', '--grpc-port=20483', '--mon-port=23341', '--ic-port=30511')' in '/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_1' (no wait)
2025-12-07 20:08:03,629 - DEBUG - ya.test.1714109  - execute: Command pid: 2056975
2025-12-07 20:08:03,629 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Started node localhost:20483/1
2025-12-07 20:08:03,631 - DEBUG - ya.test.1714109  - _get_command_output_file: Command out will be placed to ydbd.out.33
2025-12-07 20:08:03,631 - DEBUG - ya.test.1714109  - _get_command_output_file: Command err will be placed to ydbd.err.33
2025-12-07 20:08:03,632 - DEBUG - ya.test.1714109  - execute: Executing '['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-07 20:08:03,632 - DEBUG - ya.test.1714109  - execute: Command pid: 2056980
2025-12-07 20:08:04,075 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) rc: 0
2025-12-07 20:08:04,075 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) elapsed time (sec): 0.44176268577575684
2025-12-07 20:08:04,076 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) idrss: 0
2025-12-07 20:08:04,076 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) inblock: 0
2025-12-07 20:08:04,076 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) isrss: 0
2025-12-07 20:08:04,076 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) ixrss: 0
2025-12-07 20:08:04,076 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) majflt: 0
2025-12-07 20:08:04,076 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) maxrss: 547168
2025-12-07 20:08:04,076 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) minflt: 16268
2025-12-07 20:08:04,077 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) msgrcv: 0
2025-12-07 20:08:04,077 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) msgsnd: 0
2025-12-07 20:08:04,077 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) nivcsw: 18
2025-12-07 20:08:04,077 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) nsignals: 0
2025-12-07 20:08:04,077 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) nswap: 0
2025-12-07 20:08:04,077 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) nvcsw: 13
2025-12-07 20:08:04,077 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) oublock: 8
2025-12-07 20:08:04,078 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) stime: 0.264546
2025-12-07 20:08:04,078 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) utime: 0.120248
2025-12-07 20:08:04,078 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) wtime: 0.443
2025-12-07 20:08:04,078 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) output:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:08:04,078 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2056980) errors:

2025-12-07 20:08:04,079 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Node 1 version:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-07 20:08:04,079 - DEBUG - ydb.connection.1714109  - 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-07 20:08:04,081 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:04,082 - DEBUG - ydb.connection.1714109  - 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-07 20:08:04,082 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table one
2025-12-07 20:08:04,085 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-07 20:08:04,086 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Subscribe waiter <Future at 0x7f1317db1870 state=pending>
2025-12-07 20:08:04,086 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-07 20:08:04,087 - DEBUG - ydb.table.SessionPool.1714109  - _create: Created session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:04,087 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:04,088 - ERROR - ydb.table.SessionPool.1714109  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-07 20:08:04,090 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:04,090 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 5703e2d4-3553-483a-9266-c2b36428b5f7, localhost:29188): creating call state
2025-12-07 20:08:04,090 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 5703e2d4-3553-483a-9266-c2b36428b5f7, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:04,093 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 5703e2d4-3553-483a-9266-c2b36428b5f7, localhost:29188): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-07 20:08:04,093 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:29188". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-07 20:08:04,093 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:05,363 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:05,363 - DEBUG - ydb.connection.1714109  - 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-07 20:08:05,368 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:05,369 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 723f8cbd-646f-4d48-9822-a93c185c2ce7, localhost:29188): creating call state
2025-12-07 20:08:05,369 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 723f8cbd-646f-4d48-9822-a93c185c2ce7, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:05,372 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 723f8cbd-646f-4d48-9822-a93c185c2ce7, localhost:29188): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-gishsengma.auto.internal" port: 20483 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-07 20:08:05,373 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-gishsengma.auto.internal:20483, location , ssl: False>]>
2025-12-07 20:08:05,374 - DEBUG - ydb.connection.1714109  - 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-07 20:08:05,381 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:05,381 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,382 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateSession, de3854fb-93a3-43c6-87f9-681890ab2b80, ghrun-gishsengma.auto.internal:20483): creating call state
2025-12-07 20:08:05,383 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateSession, de3854fb-93a3-43c6-87f9-681890ab2b80, ghrun-gishsengma.auto.internal:20483): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:05,385 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, de3854fb-93a3-43c6-87f9-681890ab2b80, ghrun-gishsengma.auto.internal:20483): on response callback started
2025-12-07 20:08:05,386 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateSession, de3854fb-93a3-43c6-87f9-681890ab2b80, ghrun-gishsengma.auto.internal:20483): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZDhkYmUwYjUtZDkyMTJjNjQtMjE1MWEyNmItMzg2NWZlYzY=" } } } }
2025-12-07 20:08:05,387 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,387 - DEBUG - ydb.table.SessionPool.1714109  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,387 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, de3854fb-93a3-43c6-87f9-681890ab2b80, ghrun-gishsengma.auto.internal:20483): on response callback success
2025-12-07 20:08:05,388 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, 3cb0fd9b-1c60-4d29-99eb-b7312bc3da4a, ghrun-gishsengma.auto.internal:20483): creating call state
2025-12-07 20:08:05,389 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, 3cb0fd9b-1c60-4d29-99eb-b7312bc3da4a, ghrun-gishsengma.auto.internal:20483): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhkYmUwYjUtZDkyMTJjNjQtMjE1MWEyNmItMzg2NWZlYzY=" 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-07 20:08:05,434 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, 3cb0fd9b-1c60-4d29-99eb-b7312bc3da4a, ghrun-gishsengma.auto.internal:20483): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:05,434 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,435 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table two
2025-12-07 20:08:05,435 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, 3f54e9c9-9ce3-457d-bdae-045cb5b22397, ghrun-gishsengma.auto.internal:20483): creating call state
2025-12-07 20:08:05,435 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, 3f54e9c9-9ce3-457d-bdae-045cb5b22397, ghrun-gishsengma.auto.internal:20483): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhkYmUwYjUtZDkyMTJjNjQtMjE1MWEyNmItMzg2NWZlYzY=" 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-07 20:08:05,462 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, 3f54e9c9-9ce3-457d-bdae-045cb5b22397, ghrun-gishsengma.auto.internal:20483): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:05,462 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,463 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-07 20:08:05,463 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, 16981e8c-8775-4687-9ad3-93f7240b017a, ghrun-gishsengma.auto.internal:20483): creating call state
2025-12-07 20:08:05,464 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, 16981e8c-8775-4687-9ad3-93f7240b017a, ghrun-gishsengma.auto.internal:20483): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhkYmUwYjUtZDkyMTJjNjQtMjE1MWEyNmItMzg2NWZlYzY=" 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-07 20:08:05,630 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, 16981e8c-8775-4687-9ad3-93f7240b017a, ghrun-gishsengma.auto.internal:20483): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-07 20:08:05,631 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,631 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-07 20:08:05,631 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, 860a3b11-5302-4304-a861-edd4dcca730f, ghrun-gishsengma.auto.internal:20483): creating call state
2025-12-07 20:08:05,632 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, 860a3b11-5302-4304-a861-edd4dcca730f, ghrun-gishsengma.auto.internal:20483): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhkYmUwYjUtZDkyMTJjNjQtMjE1MWEyNmItMzg2NWZlYzY=" 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-07 20:08:05,664 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, 860a3b11-5302-4304-a861-edd4dcca730f, ghrun-gishsengma.auto.internal:20483): 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-07 20:08:05,666 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,666 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-07 20:08:05,667 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DropTable, bbe0f035-9d82-4831-a768-cbf09b8b40dd, ghrun-gishsengma.auto.internal:20483): creating call state
2025-12-07 20:08:05,667 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DropTable, bbe0f035-9d82-4831-a768-cbf09b8b40dd, ghrun-gishsengma.auto.internal:20483): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhkYmUwYjUtZDkyMTJjNjQtMjE1MWEyNmItMzg2NWZlYzY=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-07 20:08:05,681 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DropTable, bbe0f035-9d82-4831-a768-cbf09b8b40dd, ghrun-gishsengma.auto.internal:20483): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:05,682 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,682 - DEBUG - ydb.table.SessionPool.1714109  - stop: Requested session pool stop.
2025-12-07 20:08:05,682 - DEBUG - ydb.table.SessionPool.1714109  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-07 20:08:05,683 - DEBUG - ydb.table.SessionPool.1714109  - _terminate_event: Terminated session pool.
2025-12-07 20:08:05,683 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroying sessions in active queue
2025-12-07 20:08:05,684 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f1317db1190>, reason: session-pool-terminated
2025-12-07 20:08:05,684 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Session <ydb.table.Session object at 0x7f1317db1190> is no longer active. Current active count 0.
2025-12-07 20:08:05,684 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DeleteSession, 7d395c1f-b1a3-48d5-8d98-ad821c2dd380, ghrun-gishsengma.auto.internal:20483): creating call state
2025-12-07 20:08:05,685 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DeleteSession, 7d395c1f-b1a3-48d5-8d98-ad821c2dd380, ghrun-gishsengma.auto.internal:20483): request = { session_id: "ydb://session/3?node_id=50000&id=ZDhkYmUwYjUtZDkyMTJjNjQtMjE1MWEyNmItMzg2NWZlYzY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:05,686 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f1317db1190>
2025-12-07 20:08:05,686 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroyed active sessions
2025-12-07 20:08:05,687 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, 7d395c1f-b1a3-48d5-8d98-ad821c2dd380, ghrun-gishsengma.auto.internal:20483): on response callback started
2025-12-07 20:08:05,687 - DEBUG - ydb.topic.1714109  - close: Close topic client
2025-12-07 20:08:05,688 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DeleteSession, 7d395c1f-b1a3-48d5-8d98-ad821c2dd380, ghrun-gishsengma.auto.internal:20483): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:05,688 - DEBUG - ydb.topic.1714109  - close: Topic client was closed
2025-12-07 20:08:05,688 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, 7d395c1f-b1a3-48d5-8d98-ad821c2dd380, ghrun-gishsengma.auto.internal:20483): on response callback success
2025-12-07 20:08:05,689 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:05,689 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint ghrun-gishsengma.auto.internal:20483
2025-12-07 20:08:05,690 - INFO - ydb.pool.Discovery.1714109  - run: Successfully terminated discovery process
2025-12-07 20:08:05,690 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-07 20:08:05,691 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - remove_database: /Root/users/database
2025-12-07 20:08:05,691 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-07 20:08:05,696 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765138085693979&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-07 20:08:05,697 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-07 20:08:06,201 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-07 20:08:06,201 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-07 20:08:06,204 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-07 20:08:07,204 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - stop: Stopped node localhost:20483/1
2025-12-07 20:08:07,205 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: done 0
2025-12-07 20:08:07,205 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create_database
2025-12-07 20:08:07,718 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:08:07,719 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10877
2025-12-07 20:08:07,719 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12786
2025-12-07 20:08:07,720 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3736
2025-12-07 20:08:07,720 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10408
2025-12-07 20:08:07,720 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4789
2025-12-07 20:08:07,721 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:07,721 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_2/logfile_7lkgcagj.log --grpc-port=10877 --mon-port=12786 --ic-port=3736
2025-12-07 20:08:07,721 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:07,722 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_2/logfile_7lkgcagj.log --grpc-port=10877 --mon-port=12786 --ic-port=3736
2025-12-07 20:08:07,722 - DEBUG - ya.test.1714109  - execute: Executing '('/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29188', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_2/logfile_7lkgcagj.log', '--grpc-port=10877', '--mon-port=12786', '--ic-port=3736')' in '/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_2' (no wait)
2025-12-07 20:08:07,724 - DEBUG - ya.test.1714109  - execute: Command pid: 2062085
2025-12-07 20:08:07,724 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Started node localhost:10877/2
2025-12-07 20:08:07,725 - DEBUG - ya.test.1714109  - _get_command_output_file: Command out will be placed to ydbd.out.34
2025-12-07 20:08:07,726 - DEBUG - ya.test.1714109  - _get_command_output_file: Command err will be placed to ydbd.err.34
2025-12-07 20:08:07,726 - DEBUG - ya.test.1714109  - execute: Executing '['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-07 20:08:07,727 - DEBUG - ya.test.1714109  - execute: Command pid: 2062089
2025-12-07 20:08:08,153 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) rc: 0
2025-12-07 20:08:08,154 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) elapsed time (sec): 0.42514491081237793
2025-12-07 20:08:08,154 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) idrss: 0
2025-12-07 20:08:08,154 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) inblock: 0
2025-12-07 20:08:08,154 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) isrss: 0
2025-12-07 20:08:08,154 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) ixrss: 0
2025-12-07 20:08:08,154 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) majflt: 0
2025-12-07 20:08:08,155 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) maxrss: 547168
2025-12-07 20:08:08,155 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) minflt: 16267
2025-12-07 20:08:08,155 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) msgrcv: 0
2025-12-07 20:08:08,155 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) msgsnd: 0
2025-12-07 20:08:08,155 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) nivcsw: 3
2025-12-07 20:08:08,155 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) nsignals: 0
2025-12-07 20:08:08,156 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) nswap: 0
2025-12-07 20:08:08,156 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) nvcsw: 18
2025-12-07 20:08:08,156 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) oublock: 8
2025-12-07 20:08:08,156 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) stime: 0.23927099999999998
2025-12-07 20:08:08,156 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) utime: 0.137885
2025-12-07 20:08:08,157 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) wtime: 0.426
2025-12-07 20:08:08,157 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) output:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:08:08,157 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2062089) errors:

2025-12-07 20:08:08,157 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Node 2 version:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-07 20:08:08,158 - DEBUG - ydb.connection.1714109  - 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-07 20:08:08,160 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:08,161 - DEBUG - ydb.connection.1714109  - 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-07 20:08:08,161 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table one
2025-12-07 20:08:08,164 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-07 20:08:08,165 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Subscribe waiter <Future at 0x7f13162bb8a0 state=pending>
2025-12-07 20:08:08,166 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-07 20:08:08,166 - DEBUG - ydb.table.SessionPool.1714109  - _create: Created session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:08,166 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:08,166 - ERROR - ydb.table.SessionPool.1714109  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-07 20:08:08,168 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:08,169 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 4e4e2a3a-54e1-4c02-a38b-e4001f1103c8, localhost:29188): creating call state
2025-12-07 20:08:08,169 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 4e4e2a3a-54e1-4c02-a38b-e4001f1103c8, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:08,172 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 4e4e2a3a-54e1-4c02-a38b-e4001f1103c8, localhost:29188): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-07 20:08:08,172 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:29188". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-07 20:08:08,172 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:10,361 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:10,362 - DEBUG - ydb.connection.1714109  - 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-07 20:08:10,366 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:10,367 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 0598697d-2f16-4271-8ce5-c8d70c5db7cf, localhost:29188): creating call state
2025-12-07 20:08:10,367 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 0598697d-2f16-4271-8ce5-c8d70c5db7cf, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:10,370 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 0598697d-2f16-4271-8ce5-c8d70c5db7cf, localhost:29188): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-gishsengma.auto.internal" port: 10877 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-07 20:08:10,370 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-gishsengma.auto.internal:10877, location , ssl: False>]>
2025-12-07 20:08:10,371 - DEBUG - ydb.connection.1714109  - 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-07 20:08:10,377 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:10,378 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,379 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateSession, f4a03178-d208-40cb-b780-2dd9e263241e, ghrun-gishsengma.auto.internal:10877): creating call state
2025-12-07 20:08:10,380 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateSession, f4a03178-d208-40cb-b780-2dd9e263241e, ghrun-gishsengma.auto.internal:10877): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:10,383 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, f4a03178-d208-40cb-b780-2dd9e263241e, ghrun-gishsengma.auto.internal:10877): on response callback started
2025-12-07 20:08:10,383 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateSession, f4a03178-d208-40cb-b780-2dd9e263241e, ghrun-gishsengma.auto.internal:10877): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=ZDQwNjUxZGUtMWIzYWU1NGItNzA0MzZhNy03YTkwOTczYw==" } } } }
2025-12-07 20:08:10,384 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,384 - DEBUG - ydb.table.SessionPool.1714109  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,384 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, f4a03178-d208-40cb-b780-2dd9e263241e, ghrun-gishsengma.auto.internal:10877): on response callback success
2025-12-07 20:08:10,385 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, 7c67bfaf-21f4-4d31-a7f0-9dcd78e381e4, ghrun-gishsengma.auto.internal:10877): creating call state
2025-12-07 20:08:10,386 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, 7c67bfaf-21f4-4d31-a7f0-9dcd78e381e4, ghrun-gishsengma.auto.internal:10877): request = { session_id: "ydb://session/3?node_id=50001&id=ZDQwNjUxZGUtMWIzYWU1NGItNzA0MzZhNy03YTkwOTczYw==" 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-07 20:08:10,417 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, 7c67bfaf-21f4-4d31-a7f0-9dcd78e381e4, ghrun-gishsengma.auto.internal:10877): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:10,418 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,418 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table two
2025-12-07 20:08:10,419 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, 1aa63f52-8541-4ed4-ab2e-1ada6cbcedf4, ghrun-gishsengma.auto.internal:10877): creating call state
2025-12-07 20:08:10,419 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, 1aa63f52-8541-4ed4-ab2e-1ada6cbcedf4, ghrun-gishsengma.auto.internal:10877): request = { session_id: "ydb://session/3?node_id=50001&id=ZDQwNjUxZGUtMWIzYWU1NGItNzA0MzZhNy03YTkwOTczYw==" 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-07 20:08:10,442 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, 1aa63f52-8541-4ed4-ab2e-1ada6cbcedf4, ghrun-gishsengma.auto.internal:10877): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:10,443 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,443 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-07 20:08:10,444 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, eb7105df-a8fa-4465-a7a8-d36246e48775, ghrun-gishsengma.auto.internal:10877): creating call state
2025-12-07 20:08:10,444 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, eb7105df-a8fa-4465-a7a8-d36246e48775, ghrun-gishsengma.auto.internal:10877): request = { session_id: "ydb://session/3?node_id=50001&id=ZDQwNjUxZGUtMWIzYWU1NGItNzA0MzZhNy03YTkwOTczYw==" 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-07 20:08:10,488 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, eb7105df-a8fa-4465-a7a8-d36246e48775, ghrun-gishsengma.auto.internal:10877): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-07 20:08:10,489 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,489 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-07 20:08:10,490 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, b0cd73cd-0c4e-4841-aeab-082e7816cc64, ghrun-gishsengma.auto.internal:10877): creating call state
2025-12-07 20:08:10,491 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, b0cd73cd-0c4e-4841-aeab-082e7816cc64, ghrun-gishsengma.auto.internal:10877): request = { session_id: "ydb://session/3?node_id=50001&id=ZDQwNjUxZGUtMWIzYWU1NGItNzA0MzZhNy03YTkwOTczYw==" 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-07 20:08:10,527 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, b0cd73cd-0c4e-4841-aeab-082e7816cc64, ghrun-gishsengma.auto.internal:10877): 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-07 20:08:10,527 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,528 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-07 20:08:10,528 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DropTable, 8e3091dc-74c2-4087-8288-6194048b892d, ghrun-gishsengma.auto.internal:10877): creating call state
2025-12-07 20:08:10,528 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DropTable, 8e3091dc-74c2-4087-8288-6194048b892d, ghrun-gishsengma.auto.internal:10877): request = { session_id: "ydb://session/3?node_id=50001&id=ZDQwNjUxZGUtMWIzYWU1NGItNzA0MzZhNy03YTkwOTczYw==" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-07 20:08:10,545 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DropTable, 8e3091dc-74c2-4087-8288-6194048b892d, ghrun-gishsengma.auto.internal:10877): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:10,545 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,546 - DEBUG - ydb.table.SessionPool.1714109  - stop: Requested session pool stop.
2025-12-07 20:08:10,546 - DEBUG - ydb.table.SessionPool.1714109  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-07 20:08:10,546 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroying sessions in active queue
2025-12-07 20:08:10,546 - DEBUG - ydb.table.SessionPool.1714109  - _terminate_event: Terminated session pool.
2025-12-07 20:08:10,546 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f13162bbe90>, reason: session-pool-terminated
2025-12-07 20:08:10,547 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Session <ydb.table.Session object at 0x7f13162bbe90> is no longer active. Current active count 0.
2025-12-07 20:08:10,548 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DeleteSession, 767612d7-7ef3-4d3c-b800-43f5b29afb82, ghrun-gishsengma.auto.internal:10877): creating call state
2025-12-07 20:08:10,548 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DeleteSession, 767612d7-7ef3-4d3c-b800-43f5b29afb82, ghrun-gishsengma.auto.internal:10877): request = { session_id: "ydb://session/3?node_id=50001&id=ZDQwNjUxZGUtMWIzYWU1NGItNzA0MzZhNy03YTkwOTczYw==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:10,550 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f13162bbe90>
2025-12-07 20:08:10,550 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, 767612d7-7ef3-4d3c-b800-43f5b29afb82, ghrun-gishsengma.auto.internal:10877): on response callback started
2025-12-07 20:08:10,551 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroyed active sessions
2025-12-07 20:08:10,552 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DeleteSession, 767612d7-7ef3-4d3c-b800-43f5b29afb82, ghrun-gishsengma.auto.internal:10877): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:10,552 - DEBUG - ydb.topic.1714109  - close: Close topic client
2025-12-07 20:08:10,552 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, 767612d7-7ef3-4d3c-b800-43f5b29afb82, ghrun-gishsengma.auto.internal:10877): on response callback success
2025-12-07 20:08:10,553 - DEBUG - ydb.topic.1714109  - close: Topic client was closed
2025-12-07 20:08:10,553 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:10,569 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint ghrun-gishsengma.auto.internal:10877
2025-12-07 20:08:10,579 - INFO - ydb.pool.Discovery.1714109  - run: Successfully terminated discovery process
2025-12-07 20:08:10,580 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-07 20:08:10,580 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - remove_database: /Root/users/database
2025-12-07 20:08:10,581 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-07 20:08:10,586 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765138090582581&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-07 20:08:10,586 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-07 20:08:11,090 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-07 20:08:11,090 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-07 20:08:11,093 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-07 20:08:12,095 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - stop: Stopped node localhost:10877/2
2025-12-07 20:08:12,096 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: done 1
2025-12-07 20:08:12,096 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create_database
2025-12-07 20:08:12,610 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:08:12,610 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6704
2025-12-07 20:08:12,611 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22654
2025-12-07 20:08:12,611 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20904
2025-12-07 20:08:12,611 - DEBUG - library.python.filelock.1714109  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20904
2025-12-07 20:08:12,612 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20905
2025-12-07 20:08:12,612 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11982
2025-12-07 20:08:12,613 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17567
2025-12-07 20:08:12,613 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:12,613 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_3/logfile_5mui1j8h.log --grpc-port=6704 --mon-port=22654 --ic-port=20905
2025-12-07 20:08:12,614 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:12,614 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_3/logfile_5mui1j8h.log --grpc-port=6704 --mon-port=22654 --ic-port=20905
2025-12-07 20:08:12,614 - DEBUG - ya.test.1714109  - execute: Executing '('/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29188', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_3/logfile_5mui1j8h.log', '--grpc-port=6704', '--mon-port=22654', '--ic-port=20905')' in '/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_3' (no wait)
2025-12-07 20:08:12,615 - DEBUG - ya.test.1714109  - execute: Command pid: 2069522
2025-12-07 20:08:12,616 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Started node localhost:6704/3
2025-12-07 20:08:12,617 - DEBUG - ya.test.1714109  - _get_command_output_file: Command out will be placed to ydbd.out.35
2025-12-07 20:08:12,618 - DEBUG - ya.test.1714109  - _get_command_output_file: Command err will be placed to ydbd.err.35
2025-12-07 20:08:12,618 - DEBUG - ya.test.1714109  - execute: Executing '['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-07 20:08:12,619 - DEBUG - ya.test.1714109  - execute: Command pid: 2069523
2025-12-07 20:08:13,106 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) rc: 0
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) elapsed time (sec): 0.4868924617767334
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) idrss: 0
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) inblock: 0
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) isrss: 0
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) ixrss: 0
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) majflt: 0
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) maxrss: 547168
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) minflt: 16267
2025-12-07 20:08:13,107 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) msgrcv: 0
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) msgsnd: 0
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) nivcsw: 3
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) nsignals: 0
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) nswap: 0
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) nvcsw: 15
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) oublock: 8
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) stime: 0.329607
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) utime: 0.109065
2025-12-07 20:08:13,108 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) wtime: 0.488
2025-12-07 20:08:13,109 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) output:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:08:13,109 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2069523) errors:

2025-12-07 20:08:13,109 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Node 3 version:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-07 20:08:13,109 - DEBUG - ydb.connection.1714109  - 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-07 20:08:13,110 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:13,111 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table one
2025-12-07 20:08:13,111 - DEBUG - ydb.connection.1714109  - 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-07 20:08:13,112 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-07 20:08:13,116 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Subscribe waiter <Future at 0x7f13173104c0 state=pending>
2025-12-07 20:08:13,117 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-07 20:08:13,117 - DEBUG - ydb.table.SessionPool.1714109  - _create: Created session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:13,118 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:13,118 - ERROR - ydb.table.SessionPool.1714109  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-07 20:08:13,119 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:13,120 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 085f9bca-300c-4d8a-93b0-435f7335f319, localhost:29188): creating call state
2025-12-07 20:08:13,120 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 085f9bca-300c-4d8a-93b0-435f7335f319, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:13,122 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 085f9bca-300c-4d8a-93b0-435f7335f319, localhost:29188): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-07 20:08:13,122 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:29188". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-07 20:08:13,123 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:14,323 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:14,323 - DEBUG - ydb.connection.1714109  - 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-07 20:08:14,330 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:14,331 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 26af7e30-418d-46ed-ba31-4a293b702248, localhost:29188): creating call state
2025-12-07 20:08:14,331 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 26af7e30-418d-46ed-ba31-4a293b702248, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:14,334 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 26af7e30-418d-46ed-ba31-4a293b702248, localhost:29188): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-gishsengma.auto.internal" port: 6704 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-07 20:08:14,334 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-gishsengma.auto.internal:6704, location , ssl: False>]>
2025-12-07 20:08:14,335 - DEBUG - ydb.connection.1714109  - 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-07 20:08:14,344 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:14,344 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,345 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateSession, 5e83ea64-4c89-494c-b0de-def9b750890e, ghrun-gishsengma.auto.internal:6704): creating call state
2025-12-07 20:08:14,346 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateSession, 5e83ea64-4c89-494c-b0de-def9b750890e, ghrun-gishsengma.auto.internal:6704): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:14,348 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, 5e83ea64-4c89-494c-b0de-def9b750890e, ghrun-gishsengma.auto.internal:6704): on response callback started
2025-12-07 20:08:14,349 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateSession, 5e83ea64-4c89-494c-b0de-def9b750890e, ghrun-gishsengma.auto.internal:6704): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=OTU2ZWNhMjMtOGNhNTRkYzEtMjIwYzNhMTItZjk1NTA5ZWI=" } } } }
2025-12-07 20:08:14,349 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,350 - DEBUG - ydb.table.SessionPool.1714109  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,350 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, 5c832a38-4b1b-4ea9-9c42-938b9dff096e, ghrun-gishsengma.auto.internal:6704): creating call state
2025-12-07 20:08:14,351 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, 5e83ea64-4c89-494c-b0de-def9b750890e, ghrun-gishsengma.auto.internal:6704): on response callback success
2025-12-07 20:08:14,351 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, 5c832a38-4b1b-4ea9-9c42-938b9dff096e, ghrun-gishsengma.auto.internal:6704): request = { session_id: "ydb://session/3?node_id=50002&id=OTU2ZWNhMjMtOGNhNTRkYzEtMjIwYzNhMTItZjk1NTA5ZWI=" 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-07 20:08:14,398 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, 5c832a38-4b1b-4ea9-9c42-938b9dff096e, ghrun-gishsengma.auto.internal:6704): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:14,398 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,399 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table two
2025-12-07 20:08:14,399 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, 12768baa-3188-4f92-b921-1867b7a6706a, ghrun-gishsengma.auto.internal:6704): creating call state
2025-12-07 20:08:14,400 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, 12768baa-3188-4f92-b921-1867b7a6706a, ghrun-gishsengma.auto.internal:6704): request = { session_id: "ydb://session/3?node_id=50002&id=OTU2ZWNhMjMtOGNhNTRkYzEtMjIwYzNhMTItZjk1NTA5ZWI=" 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-07 20:08:14,452 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, 12768baa-3188-4f92-b921-1867b7a6706a, ghrun-gishsengma.auto.internal:6704): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:14,453 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,454 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-07 20:08:14,455 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, 53e2afc8-a201-477e-a403-091fe0968f20, ghrun-gishsengma.auto.internal:6704): creating call state
2025-12-07 20:08:14,455 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, 53e2afc8-a201-477e-a403-091fe0968f20, ghrun-gishsengma.auto.internal:6704): request = { session_id: "ydb://session/3?node_id=50002&id=OTU2ZWNhMjMtOGNhNTRkYzEtMjIwYzNhMTItZjk1NTA5ZWI=" 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-07 20:08:14,662 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, 53e2afc8-a201-477e-a403-091fe0968f20, ghrun-gishsengma.auto.internal:6704): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-07 20:08:14,663 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,663 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-07 20:08:14,664 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, 0fb23ca3-83e1-4b90-b8f0-25eb318dea91, ghrun-gishsengma.auto.internal:6704): creating call state
2025-12-07 20:08:14,664 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, 0fb23ca3-83e1-4b90-b8f0-25eb318dea91, ghrun-gishsengma.auto.internal:6704): request = { session_id: "ydb://session/3?node_id=50002&id=OTU2ZWNhMjMtOGNhNTRkYzEtMjIwYzNhMTItZjk1NTA5ZWI=" 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-07 20:08:14,711 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, 0fb23ca3-83e1-4b90-b8f0-25eb318dea91, ghrun-gishsengma.auto.internal:6704): 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-07 20:08:14,712 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,712 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-07 20:08:14,713 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DropTable, 2a48ba02-678f-482f-be12-6f991e55777c, ghrun-gishsengma.auto.internal:6704): creating call state
2025-12-07 20:08:14,714 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DropTable, 2a48ba02-678f-482f-be12-6f991e55777c, ghrun-gishsengma.auto.internal:6704): request = { session_id: "ydb://session/3?node_id=50002&id=OTU2ZWNhMjMtOGNhNTRkYzEtMjIwYzNhMTItZjk1NTA5ZWI=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-07 20:08:14,745 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DropTable, 2a48ba02-678f-482f-be12-6f991e55777c, ghrun-gishsengma.auto.internal:6704): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:14,746 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,746 - DEBUG - ydb.table.SessionPool.1714109  - stop: Requested session pool stop.
2025-12-07 20:08:14,746 - DEBUG - ydb.table.SessionPool.1714109  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-07 20:08:14,747 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroying sessions in active queue
2025-12-07 20:08:14,747 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f13173132b0>, reason: session-pool-terminated
2025-12-07 20:08:14,748 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Session <ydb.table.Session object at 0x7f13173132b0> is no longer active. Current active count 0.
2025-12-07 20:08:14,748 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DeleteSession, a0d5e80c-a456-4928-a0fb-053e3c633153, ghrun-gishsengma.auto.internal:6704): creating call state
2025-12-07 20:08:14,748 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DeleteSession, a0d5e80c-a456-4928-a0fb-053e3c633153, ghrun-gishsengma.auto.internal:6704): request = { session_id: "ydb://session/3?node_id=50002&id=OTU2ZWNhMjMtOGNhNTRkYzEtMjIwYzNhMTItZjk1NTA5ZWI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:14,747 - DEBUG - ydb.table.SessionPool.1714109  - _terminate_event: Terminated session pool.
2025-12-07 20:08:14,751 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f13173132b0>
2025-12-07 20:08:14,751 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, a0d5e80c-a456-4928-a0fb-053e3c633153, ghrun-gishsengma.auto.internal:6704): on response callback started
2025-12-07 20:08:14,758 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroyed active sessions
2025-12-07 20:08:14,759 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DeleteSession, a0d5e80c-a456-4928-a0fb-053e3c633153, ghrun-gishsengma.auto.internal:6704): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:14,759 - DEBUG - ydb.topic.1714109  - close: Close topic client
2025-12-07 20:08:14,760 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, a0d5e80c-a456-4928-a0fb-053e3c633153, ghrun-gishsengma.auto.internal:6704): on response callback success
2025-12-07 20:08:14,760 - DEBUG - ydb.topic.1714109  - close: Topic client was closed
2025-12-07 20:08:14,761 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:14,762 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint ghrun-gishsengma.auto.internal:6704
2025-12-07 20:08:14,763 - INFO - ydb.pool.Discovery.1714109  - run: Successfully terminated discovery process
2025-12-07 20:08:14,763 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-07 20:08:14,764 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - remove_database: /Root/users/database
2025-12-07 20:08:14,764 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-07 20:08:14,769 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765138094766639&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-07 20:08:14,770 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-07 20:08:15,277 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-07 20:08:15,277 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-07 20:08:15,281 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-07 20:08:16,282 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - stop: Stopped node localhost:6704/3
2025-12-07 20:08:16,283 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: done 2
2025-12-07 20:08:16,283 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create_database
2025-12-07 20:08:16,795 - DEBUG - ya.test.1714109  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd
2025-12-07 20:08:16,796 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9928
2025-12-07 20:08:16,796 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6102
2025-12-07 20:08:16,796 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26434
2025-12-07 20:08:16,797 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19600
2025-12-07 20:08:16,797 - DEBUG - library.python.filelock.1714109  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13199
2025-12-07 20:08:16,798 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:16,798 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_4/logfile_9g8mz3sr.log --grpc-port=9928 --mon-port=6102 --ic-port=26434
2025-12-07 20:08:16,798 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs"
2025-12-07 20:08:16,798 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:29188 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_4/logfile_9g8mz3sr.log --grpc-port=9928 --mon-port=6102 --ic-port=26434
2025-12-07 20:08:16,799 - DEBUG - ya.test.1714109  - execute: Executing '('/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:29188', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_4/logfile_9g8mz3sr.log', '--grpc-port=9928', '--mon-port=6102', '--ic-port=26434')' in '/home/runner/.ya/build/build_root/8xn2/004476/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--false/cluster/slot_4' (no wait)
2025-12-07 20:08:16,800 - DEBUG - ya.test.1714109  - execute: Command pid: 2076323
2025-12-07 20:08:16,801 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Started node localhost:9928/4
2025-12-07 20:08:16,802 - DEBUG - ya.test.1714109  - _get_command_output_file: Command out will be placed to ydbd.out.36
2025-12-07 20:08:16,803 - DEBUG - ya.test.1714109  - _get_command_output_file: Command err will be placed to ydbd.err.36
2025-12-07 20:08:16,803 - DEBUG - ya.test.1714109  - execute: Executing '['/home/runner/.ya/build/build_root/8xn2/004476/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-07 20:08:16,804 - DEBUG - ya.test.1714109  - execute: Command pid: 2076326
2025-12-07 20:08:17,286 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) rc: 0
2025-12-07 20:08:17,286 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) elapsed time (sec): 0.4813063144683838
2025-12-07 20:08:17,286 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) idrss: 0
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) inblock: 0
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) isrss: 0
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) ixrss: 0
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) majflt: 2
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) maxrss: 547168
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) minflt: 17640
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) msgrcv: 0
2025-12-07 20:08:17,287 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) msgsnd: 0
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) nivcsw: 16
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) nsignals: 0
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) nswap: 0
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) nvcsw: 17
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) oublock: 8
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) stime: 0.293543
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) utime: 0.134871
2025-12-07 20:08:17,288 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) wtime: 0.482
2025-12-07 20:08:17,289 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) output:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:08:17,289 - DEBUG - ya.test.1714109  - _save_outputs: Command (pid 2076326) errors:

2025-12-07 20:08:17,289 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - start: Node 4 version:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-gishsengma    Host information: 
        Linux ghrun-gishsengma 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-07 20:08:17,289 - DEBUG - ydb.connection.1714109  - 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-07 20:08:17,291 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:17,292 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table one
2025-12-07 20:08:17,292 - DEBUG - ydb.connection.1714109  - 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-07 20:08:17,292 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-07 20:08:17,296 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Subscribe waiter <Future at 0x7f1317f29aa0 state=pending>
2025-12-07 20:08:17,297 - DEBUG - ydb.table.SessionPool.1714109  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-07 20:08:17,297 - DEBUG - ydb.table.SessionPool.1714109  - _create: Created session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:17,297 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:17,298 - ERROR - ydb.table.SessionPool.1714109  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-07 20:08:17,299 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:17,300 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 579a54fb-507c-462c-ae1f-4240f6c008db, localhost:29188): creating call state
2025-12-07 20:08:17,300 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 579a54fb-507c-462c-ae1f-4240f6c008db, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:17,303 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 579a54fb-507c-462c-ae1f-4240f6c008db, localhost:29188): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-07 20:08:17,303 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:29188". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-07 20:08:17,303 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:19,157 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:08:19,158 - DEBUG - ydb.connection.1714109  - 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-07 20:08:19,163 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-07 20:08:19,164 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ListEndpoints, 487a1ffc-842b-4e68-b4c5-65cdfb46c2f2, localhost:29188): creating call state
2025-12-07 20:08:19,164 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ListEndpoints, 487a1ffc-842b-4e68-b4c5-65cdfb46c2f2, localhost:29188): request = { database: "/Root/users/database" }
2025-12-07 20:08:19,171 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ListEndpoints, 487a1ffc-842b-4e68-b4c5-65cdfb46c2f2, localhost:29188): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-gishsengma.auto.internal" port: 9928 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-07 20:08:19,172 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1714109  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-gishsengma.auto.internal:9928, location , ssl: False>]>
2025-12-07 20:08:19,172 - DEBUG - ydb.connection.1714109  - 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-07 20:08:19,180 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:19,180 - DEBUG - ydb.table.SessionPool.1714109  - _prepare: Preparing session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,181 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateSession, 343a2f96-f452-43d8-8d56-642cb3ab1083, ghrun-gishsengma.auto.internal:9928): creating call state
2025-12-07 20:08:19,182 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateSession, 343a2f96-f452-43d8-8d56-642cb3ab1083, ghrun-gishsengma.auto.internal:9928): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:19,184 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, 343a2f96-f452-43d8-8d56-642cb3ab1083, ghrun-gishsengma.auto.internal:9928): on response callback started
2025-12-07 20:08:19,185 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateSession, 343a2f96-f452-43d8-8d56-642cb3ab1083, ghrun-gishsengma.auto.internal:9928): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=OGI1NmMxMjgtODNjMmRmNjItYzViZDdjOTUtNWJkYjk4ODM=" } } } }
2025-12-07 20:08:19,186 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,187 - DEBUG - ydb.table.SessionPool.1714109  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,187 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, f820f1fb-4193-4853-ac8d-3c5e1a491f31, ghrun-gishsengma.auto.internal:9928): creating call state
2025-12-07 20:08:19,188 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(CreateSession, 343a2f96-f452-43d8-8d56-642cb3ab1083, ghrun-gishsengma.auto.internal:9928): on response callback success
2025-12-07 20:08:19,188 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, f820f1fb-4193-4853-ac8d-3c5e1a491f31, ghrun-gishsengma.auto.internal:9928): request = { session_id: "ydb://session/3?node_id=50003&id=OGI1NmMxMjgtODNjMmRmNjItYzViZDdjOTUtNWJkYjk4ODM=" 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-07 20:08:19,228 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, f820f1fb-4193-4853-ac8d-3c5e1a491f31, ghrun-gishsengma.auto.internal:9928): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:19,229 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,229 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: create table two
2025-12-07 20:08:19,229 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(CreateTable, 1f9d8d5d-769a-4f13-bb4e-d2e3c643e535, ghrun-gishsengma.auto.internal:9928): creating call state
2025-12-07 20:08:19,230 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(CreateTable, 1f9d8d5d-769a-4f13-bb4e-d2e3c643e535, ghrun-gishsengma.auto.internal:9928): request = { session_id: "ydb://session/3?node_id=50003&id=OGI1NmMxMjgtODNjMmRmNjItYzViZDdjOTUtNWJkYjk4ODM=" 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-07 20:08:19,262 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(CreateTable, 1f9d8d5d-769a-4f13-bb4e-d2e3c643e535, ghrun-gishsengma.auto.internal:9928): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:19,263 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,263 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-07 20:08:19,263 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, 7ac79ed6-48d1-4dab-b39b-a86ebf79ecaf, ghrun-gishsengma.auto.internal:9928): creating call state
2025-12-07 20:08:19,264 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, 7ac79ed6-48d1-4dab-b39b-a86ebf79ecaf, ghrun-gishsengma.auto.internal:9928): request = { session_id: "ydb://session/3?node_id=50003&id=OGI1NmMxMjgtODNjMmRmNjItYzViZDdjOTUtNWJkYjk4ODM=" 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-07 20:08:19,319 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, 7ac79ed6-48d1-4dab-b39b-a86ebf79ecaf, ghrun-gishsengma.auto.internal:9928): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-07 20:08:19,320 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,320 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-07 20:08:19,320 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(ExecuteDataQuery, bf2ddd5a-e173-4494-ac95-56f7a6a737bd, ghrun-gishsengma.auto.internal:9928): creating call state
2025-12-07 20:08:19,321 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(ExecuteDataQuery, bf2ddd5a-e173-4494-ac95-56f7a6a737bd, ghrun-gishsengma.auto.internal:9928): request = { session_id: "ydb://session/3?node_id=50003&id=OGI1NmMxMjgtODNjMmRmNjItYzViZDdjOTUtNWJkYjk4ODM=" 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-07 20:08:19,353 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(ExecuteDataQuery, bf2ddd5a-e173-4494-ac95-56f7a6a737bd, ghrun-gishsengma.auto.internal:9928): 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-07 20:08:19,354 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,354 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-07 20:08:19,355 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DropTable, 8fbbc9e3-6a45-4667-be9d-a11659cd8fe6, ghrun-gishsengma.auto.internal:9928): creating call state
2025-12-07 20:08:19,355 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DropTable, 8fbbc9e3-6a45-4667-be9d-a11659cd8fe6, ghrun-gishsengma.auto.internal:9928): request = { session_id: "ydb://session/3?node_id=50003&id=OGI1NmMxMjgtODNjMmRmNjItYzViZDdjOTUtNWJkYjk4ODM=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-07 20:08:19,372 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DropTable, 8fbbc9e3-6a45-4667-be9d-a11659cd8fe6, ghrun-gishsengma.auto.internal:9928): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:19,373 - DEBUG - ydb.table.SessionPool.1714109  - put: Put on session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,373 - DEBUG - ydb.table.SessionPool.1714109  - stop: Requested session pool stop.
2025-12-07 20:08:19,373 - DEBUG - ydb.table.SessionPool.1714109  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-07 20:08:19,374 - DEBUG - ydb.table.SessionPool.1714109  - _terminate_event: Terminated session pool.
2025-12-07 20:08:19,374 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroying sessions in active queue
2025-12-07 20:08:19,375 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f1317e56db0>, reason: session-pool-terminated
2025-12-07 20:08:19,375 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Session <ydb.table.Session object at 0x7f1317e56db0> is no longer active. Current active count 0.
2025-12-07 20:08:19,375 - DEBUG - ydb.connection.1714109  - _prepare_call: RpcState(DeleteSession, f74c4519-a918-4de4-b2f5-df273a205db1, ghrun-gishsengma.auto.internal:9928): creating call state
2025-12-07 20:08:19,376 - DEBUG - ydb.connection.1714109  - _log_request: RpcState(DeleteSession, f74c4519-a918-4de4-b2f5-df273a205db1, ghrun-gishsengma.auto.internal:9928): request = { session_id: "ydb://session/3?node_id=50003&id=OGI1NmMxMjgtODNjMmRmNjItYzViZDdjOTUtNWJkYjk4ODM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-07 20:08:19,377 - DEBUG - ydb.table.SessionPool.1714109  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f1317e56db0>
2025-12-07 20:08:19,377 - DEBUG - ydb.table.SessionPool.1714109  - stop: Destroyed active sessions
2025-12-07 20:08:19,378 - DEBUG - ydb.topic.1714109  - close: Close topic client
2025-12-07 20:08:19,378 - DEBUG - ydb.topic.1714109  - close: Topic client was closed
2025-12-07 20:08:19,378 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, f74c4519-a918-4de4-b2f5-df273a205db1, ghrun-gishsengma.auto.internal:9928): on response callback started
2025-12-07 20:08:19,379 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint localhost:29188
2025-12-07 20:08:19,379 - DEBUG - ydb.connection.1714109  - close: Closing channel for endpoint ghrun-gishsengma.auto.internal:9928
2025-12-07 20:08:19,379 - DEBUG - ydb.connection.1714109  - _log_response: RpcState(DeleteSession, f74c4519-a918-4de4-b2f5-df273a205db1, ghrun-gishsengma.auto.internal:9928): response = { operation { ready: true status: SUCCESS } }
2025-12-07 20:08:19,380 - INFO - ydb.pool.Discovery.1714109  - run: Successfully terminated discovery process
2025-12-07 20:08:19,380 - DEBUG - ydb.connection.1714109  - _on_response_callback: RpcState(DeleteSession, f74c4519-a918-4de4-b2f5-df273a205db1, ghrun-gishsengma.auto.internal:9928): on response callback success
2025-12-07 20:08:19,381 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-07 20:08:19,381 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - remove_database: /Root/users/database
2025-12-07 20:08:19,382 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-07 20:08:19,386 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765138099383836&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-07 20:08:19,386 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-07 20:08:19,893 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-07 20:08:19,893 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-07 20:08:19,896 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1714109  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-07 20:08:20,897 - INFO - ydb.tests.library.harness.kikimr_runner.1714109  - stop: Stopped node localhost:9928/4
2025-12-07 20:08:20,898 - DEBUG - __tests__.test_dynamic_tenants.1714109  - test_create_and_drop_the_same_tenant2: done 3
2025-12-07 20:08:20,899 - INFO - ya.test.1714109  - pytest_runtest_teardown: Test teardown
