2025-12-04 21:04:08,401 - INFO - ya.test.2492558  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:04:08,401 - INFO - ya.test.2492558  - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true]
2025-12-04 21:04:08,401 - INFO - ya.test.2492558  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:04:08,402 - INFO - ya.test.2492558  - pytest_runtest_setup: Test setup
2025-12-04 21:04:08,402 - INFO - ydb.tests.library.fixtures.2492558  - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-12-04 21:04:15,403 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - stop: Stopped node localhost:14881/1
2025-12-04 21:04:15,404 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9489
2025-12-04 21:04:15,405 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1938
2025-12-04 21:04:15,405 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10283
2025-12-04 21:04:15,405 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12263
2025-12-04 21:04:15,405 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32412
2025-12-04 21:04:15,405 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19477
2025-12-04 21:04:15,406 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63978
2025-12-04 21:04:15,406 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12351
2025-12-04 21:04:15,406 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61468
2025-12-04 21:04:15,406 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20534
2025-12-04 21:04:15,406 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9274
2025-12-04 21:04:15,406 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63532
2025-12-04 21:04:15,407 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3740
2025-12-04 21:04:15,407 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17704
2025-12-04 21:04:15,407 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11972
2025-12-04 21:04:15,407 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28073
2025-12-04 21:04:15,407 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6889
2025-12-04 21:04:15,407 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19634
2025-12-04 21:04:15,408 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4616
2025-12-04 21:04:15,408 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6727
2025-12-04 21:04:15,408 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4310
2025-12-04 21:04:15,408 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8223
2025-12-04 21:04:15,408 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13270
2025-12-04 21:04:15,408 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14881
2025-12-04 21:04:15,409 - DEBUG - library.python.filelock.2492558  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12157
2025-12-04 21:04:15,462 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:15,463 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32221
2025-12-04 21:04:15,463 - INFO - ydb.tests.library.fixtures.2492558  - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-12-04 21:04:15,464 - INFO - ydb.tests.library.fixtures.2492558  - ydb_cluster: setup ydb_cluster as local
2025-12-04 21:04:15,464 - DEBUG - ydb.tests.library.harness.kikimr_runner.2492558  - start: Working directory: /home/runner/.ya/build/build_root/6mdl/004faa/r3tmp/kikimr_cluster__mm_w8u5
2025-12-04 21:04:15,497 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:15,497 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28595
2025-12-04 21:04:15,498 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13634
2025-12-04 21:04:15,498 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1152
2025-12-04 21:04:15,498 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26002
2025-12-04 21:04:15,499 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:15,499 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/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/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_nt_uf2z9.log --grpc-port=28595 --mon-port=13634 --ic-port=32221
2025-12-04 21:04:15,499 - DEBUG - ydb.tests.library.harness.kikimr_runner.2492558  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:28595/1, disk_size 68719476736
2025-12-04 21:04:15,499 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:15,500 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/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/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_nt_uf2z9.log --grpc-port=28595 --mon-port=13634 --ic-port=32221
2025-12-04 21:04:15,500 - DEBUG - ya.test.2492558  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_nt_uf2z9.log', '--grpc-port=28595', '--mon-port=13634', '--ic-port=32221')' in '/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-12-04 21:04:15,501 - DEBUG - ya.test.2492558  - execute: Command pid: 2634664
2025-12-04 21:04:15,502 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Started node localhost:28595/1
2025-12-04 21:04:15,503 - DEBUG - ya.test.2492558  - _get_command_output_file: Command out will be placed to ydbd.out.37
2025-12-04 21:04:15,504 - DEBUG - ya.test.2492558  - _get_command_output_file: Command err will be placed to ydbd.err.37
2025-12-04 21:04:15,504 - DEBUG - ya.test.2492558  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:04:15,505 - DEBUG - ya.test.2492558  - execute: Command pid: 2634673
2025-12-04 21:04:15,921 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) rc: 0
2025-12-04 21:04:15,922 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) elapsed time (sec): 0.41030216217041016
2025-12-04 21:04:15,922 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) idrss: 0
2025-12-04 21:04:15,926 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) inblock: 0
2025-12-04 21:04:15,926 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) isrss: 0
2025-12-04 21:04:15,926 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) ixrss: 0
2025-12-04 21:04:15,926 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) majflt: 1
2025-12-04 21:04:15,926 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) maxrss: 546888
2025-12-04 21:04:15,927 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) minflt: 17820
2025-12-04 21:04:15,927 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) msgrcv: 0
2025-12-04 21:04:15,927 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) msgsnd: 0
2025-12-04 21:04:15,927 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) nivcsw: 65
2025-12-04 21:04:15,927 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) nsignals: 0
2025-12-04 21:04:15,927 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) nswap: 0
2025-12-04 21:04:15,927 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) nvcsw: 20
2025-12-04 21:04:15,928 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) oublock: 8
2025-12-04 21:04:15,928 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) stime: 0.255517
2025-12-04 21:04:15,928 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) utime: 0.10545099999999999
2025-12-04 21:04:15,928 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) wtime: 0.411
2025-12-04 21:04:15,928 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:04:15,929 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2634673) errors:

2025-12-04 21:04:15,929 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Node 1 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:04:17,593 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:17,593 - DEBUG - ydb.tests.library.harness.kikimr_runner.2492558  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28595', '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: 32221\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-04 21:04:17,595 - DEBUG - ya.test.2492558  - _get_command_output_file: Command out will be placed to ydbd.out.38
2025-12-04 21:04:17,596 - DEBUG - ya.test.2492558  - _get_command_output_file: Command err will be placed to ydbd.err.38
2025-12-04 21:04:17,596 - DEBUG - ya.test.2492558  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28595', '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: 32221\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 21:04:17,597 - DEBUG - ya.test.2492558  - execute: Command pid: 2636121
2025-12-04 21:04:17,987 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) rc: 0
2025-12-04 21:04:17,987 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) elapsed time (sec): 0.38923001289367676
2025-12-04 21:04:17,987 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) idrss: 0
2025-12-04 21:04:17,987 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) inblock: 0
2025-12-04 21:04:17,987 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) isrss: 0
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) ixrss: 0
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) majflt: 0
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) maxrss: 555404
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) minflt: 18401
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) msgrcv: 0
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) msgsnd: 0
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) nivcsw: 0
2025-12-04 21:04:17,988 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) nsignals: 0
2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) nswap: 0
2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) nvcsw: 115
2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) oublock: 8
2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) stime: 0.257652
2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) utime: 0.08454199999999999
2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) wtime: 0.39
2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-04 21:04:17,989 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636121) errors:

2025-12-04 21:04:17,990 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:17,990 - DEBUG - ydb.tests.library.harness.kikimr_runner.2492558  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28595', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-04 21:04:17,991 - DEBUG - ya.test.2492558  - _get_command_output_file: Command out will be placed to ydbd.out.39
2025-12-04 21:04:17,992 - DEBUG - ya.test.2492558  - _get_command_output_file: Command err will be placed to ydbd.err.39
2025-12-04 21:04:17,992 - DEBUG - ya.test.2492558  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28595', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 21:04:17,993 - DEBUG - ya.test.2492558  - execute: Command pid: 2636542
2025-12-04 21:04:18,315 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) rc: 0
2025-12-04 21:04:18,315 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) elapsed time (sec): 0.32135009765625
2025-12-04 21:04:18,315 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) idrss: 0
2025-12-04 21:04:18,315 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) inblock: 0
2025-12-04 21:04:18,315 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) isrss: 0
2025-12-04 21:04:18,315 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) ixrss: 0
2025-12-04 21:04:18,315 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) majflt: 0
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) maxrss: 555400
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) minflt: 18404
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) msgrcv: 0
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) msgsnd: 0
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) nivcsw: 6
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) nsignals: 0
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) nswap: 0
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) nvcsw: 136
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) oublock: 8
2025-12-04 21:04:18,316 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) stime: 0.179278
2025-12-04 21:04:18,317 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) utime: 0.097788
2025-12-04 21:04:18,317 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) wtime: 0.322
2025-12-04 21:04:18,317 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-04 21:04:18,317 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2636542) errors:

2025-12-04 21:04:18,317 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-04 21:04:18,328 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __run: Cluster started and initialized
2025-12-04 21:04:18,335 - INFO - ya.test.2492558  - pytest_runtest_call: Test call (class_name: test_dynamic_tenants.py, test_name: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true])
2025-12-04 21:04:18,336 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 21:04:18,845 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:18,845 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1850
2025-12-04 21:04:18,847 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5116
2025-12-04 21:04:18,848 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18173
2025-12-04 21:04:18,848 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20593
2025-12-04 21:04:18,849 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62668
2025-12-04 21:04:18,849 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:18,849 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_zii8ese7.log --grpc-port=1850 --mon-port=5116 --ic-port=18173
2025-12-04 21:04:18,849 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:18,850 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_zii8ese7.log --grpc-port=1850 --mon-port=5116 --ic-port=18173
2025-12-04 21:04:18,850 - DEBUG - ya.test.2492558  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:28595', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_zii8ese7.log', '--grpc-port=1850', '--mon-port=5116', '--ic-port=18173')' in '/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-12-04 21:04:18,851 - DEBUG - ya.test.2492558  - execute: Command pid: 2637057
2025-12-04 21:04:18,852 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Started node localhost:1850/1
2025-12-04 21:04:18,853 - DEBUG - ya.test.2492558  - _get_command_output_file: Command out will be placed to ydbd.out.40
2025-12-04 21:04:18,853 - DEBUG - ya.test.2492558  - _get_command_output_file: Command err will be placed to ydbd.err.40
2025-12-04 21:04:18,853 - DEBUG - ya.test.2492558  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:04:18,854 - DEBUG - ya.test.2492558  - execute: Command pid: 2637059
2025-12-04 21:04:19,267 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) rc: 0
2025-12-04 21:04:19,267 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) elapsed time (sec): 0.41227006912231445
2025-12-04 21:04:19,267 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) idrss: 0
2025-12-04 21:04:19,267 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) inblock: 0
2025-12-04 21:04:19,268 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) isrss: 0
2025-12-04 21:04:19,268 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) ixrss: 0
2025-12-04 21:04:19,268 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) majflt: 0
2025-12-04 21:04:19,268 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) maxrss: 546892
2025-12-04 21:04:19,269 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) minflt: 16263
2025-12-04 21:04:19,269 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) msgrcv: 0
2025-12-04 21:04:19,270 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) msgsnd: 0
2025-12-04 21:04:19,270 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) nivcsw: 0
2025-12-04 21:04:19,270 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) nsignals: 0
2025-12-04 21:04:19,270 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) nswap: 0
2025-12-04 21:04:19,270 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) nvcsw: 17
2025-12-04 21:04:19,270 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) oublock: 8
2025-12-04 21:04:19,271 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) stime: 0.262837
2025-12-04 21:04:19,271 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) utime: 0.101156
2025-12-04 21:04:19,271 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) wtime: 0.413
2025-12-04 21:04:19,271 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:04:19,272 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2637059) errors:

2025-12-04 21:04:19,272 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Node 1 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:04:19,272 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:19,273 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:19,274 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:19,274 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 21:04:19,276 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 21:04:19,277 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Subscribe waiter <Future at 0x7f61d0556590 state=pending>
2025-12-04 21:04:19,277 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 21:04:19,277 - DEBUG - ydb.table.SessionPool.2492558  - _create: Created session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:19,278 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:19,278 - ERROR - ydb.table.SessionPool.2492558  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 21:04:19,279 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 21:04:19,279 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ListEndpoints, ce550d0a-ac95-4af6-a9ab-ef4290f3ad7d, localhost:28595): creating call state
2025-12-04 21:04:19,279 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ListEndpoints, ce550d0a-ac95-4af6-a9ab-ef4290f3ad7d, localhost:28595): request = { database: "/Root/users/database" }
2025-12-04 21:04:19,281 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ListEndpoints, ce550d0a-ac95-4af6-a9ab-ef4290f3ad7d, localhost:28595): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-04 21:04:19,282 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:28595". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-04 21:04:19,282 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:20,617 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:20,618 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:20,622 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 21:04:20,623 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ListEndpoints, 172a8dfc-dc86-40c9-9842-f5550c9ddc21, localhost:28595): creating call state
2025-12-04 21:04:20,623 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ListEndpoints, 172a8dfc-dc86-40c9-9842-f5550c9ddc21, localhost:28595): request = { database: "/Root/users/database" }
2025-12-04 21:04:20,625 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ListEndpoints, 172a8dfc-dc86-40c9-9842-f5550c9ddc21, localhost:28595): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-if77zlumgq.auto.internal" port: 1850 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-12-04 21:04:20,625 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-if77zlumgq.auto.internal:1850, location , ssl: False>]>
2025-12-04 21:04:20,626 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:20,631 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:20,632 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:20,632 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateSession, 027a9e8d-28e5-48a6-be88-423f489ee00d, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:20,633 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateSession, 027a9e8d-28e5-48a6-be88-423f489ee00d, ghrun-if77zlumgq.auto.internal:1850): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:20,635 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, 027a9e8d-28e5-48a6-be88-423f489ee00d, ghrun-if77zlumgq.auto.internal:1850): on response callback started
2025-12-04 21:04:20,635 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateSession, 027a9e8d-28e5-48a6-be88-423f489ee00d, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" } } } }
2025-12-04 21:04:20,635 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:20,636 - DEBUG - ydb.table.SessionPool.2492558  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:20,636 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, 027a9e8d-28e5-48a6-be88-423f489ee00d, ghrun-if77zlumgq.auto.internal:1850): on response callback success
2025-12-04 21:04:20,636 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, dd364d7e-33b5-4733-8de4-1fa89c74a740, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:20,637 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, dd364d7e-33b5-4733-8de4-1fa89c74a740, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:20,641 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, dd364d7e-33b5-4733-8de4-1fa89c74a740, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-04 21:04:20,642 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:20,691 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 3c59fcf1-57f6-4282-9802-1152f274ffff, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:20,692 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 3c59fcf1-57f6-4282-9802-1152f274ffff, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:20,695 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 3c59fcf1-57f6-4282-9802-1152f274ffff, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-04 21:04:20,696 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:20,788 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 849af6d2-0410-4cf8-8506-796176fea03f, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:20,789 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 849af6d2-0410-4cf8-8506-796176fea03f, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:20,793 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 849af6d2-0410-4cf8-8506-796176fea03f, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-04 21:04:20,794 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:20,978 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 409b0515-f25d-455d-aa83-cad200845cb7, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:20,978 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 409b0515-f25d-455d-aa83-cad200845cb7, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:20,982 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 409b0515-f25d-455d-aa83-cad200845cb7, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-04 21:04:20,982 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:21,363 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, c099c678-780f-4c99-ad3a-1d71ebad5e7c, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:21,364 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, c099c678-780f-4c99-ad3a-1d71ebad5e7c, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:21,368 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, c099c678-780f-4c99-ad3a-1d71ebad5e7c, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-04 21:04:21,368 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:22,102 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 2760c376-f818-49db-962c-45ed750d238c, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:22,103 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 2760c376-f818-49db-962c-45ed750d238c, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:22,130 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 2760c376-f818-49db-962c-45ed750d238c, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:22,130 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:22,130 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 21:04:22,131 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 5f963feb-7ccc-4684-91aa-15144c74a112, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:22,131 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 5f963feb-7ccc-4684-91aa-15144c74a112, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:22,149 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 5f963feb-7ccc-4684-91aa-15144c74a112, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:22,150 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:22,150 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 21:04:22,151 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, af037139-a987-428c-bdb8-568900a5c459, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:22,152 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, af037139-a987-428c-bdb8-568900a5c459, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_0\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_0\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:22,179 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, af037139-a987-428c-bdb8-568900a5c459, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 21:04:22,179 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:22,180 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 21:04:22,180 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, c2bb8336-5438-4658-baca-69458ccd1698, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:22,181 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, c2bb8336-5438-4658-baca-69458ccd1698, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:22,210 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, c2bb8336-5438-4658-baca-69458ccd1698, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-04 21:04:22,211 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:22,211 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 21:04:22,212 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DropTable, 4c06e535-99c9-475b-b1f0-417e594138ac, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:22,212 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DropTable, 4c06e535-99c9-475b-b1f0-417e594138ac, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:22,224 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DropTable, 4c06e535-99c9-475b-b1f0-417e594138ac, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:22,225 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:22,225 - DEBUG - ydb.table.SessionPool.2492558  - stop: Requested session pool stop.
2025-12-04 21:04:22,226 - DEBUG - ydb.table.SessionPool.2492558  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 21:04:22,226 - DEBUG - ydb.table.SessionPool.2492558  - _terminate_event: Terminated session pool.
2025-12-04 21:04:22,226 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroying sessions in active queue
2025-12-04 21:04:22,227 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f61d05572b0>, reason: session-pool-terminated
2025-12-04 21:04:22,227 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Session <ydb.table.Session object at 0x7f61d05572b0> is no longer active. Current active count 0.
2025-12-04 21:04:22,227 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DeleteSession, c62ae488-f927-415f-83ee-9c861083b8ff, ghrun-if77zlumgq.auto.internal:1850): creating call state
2025-12-04 21:04:22,228 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DeleteSession, c62ae488-f927-415f-83ee-9c861083b8ff, ghrun-if77zlumgq.auto.internal:1850): request = { session_id: "ydb://session/3?node_id=50000&id=ZjBhZWE4ZjctNTRmYmI5ODEtMzQ5N2IxMTAtMmZkNGFmYmU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:22,228 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f61d05572b0>
2025-12-04 21:04:22,229 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroyed active sessions
2025-12-04 21:04:22,229 - DEBUG - ydb.topic.2492558  - close: Close topic client
2025-12-04 21:04:22,229 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, c62ae488-f927-415f-83ee-9c861083b8ff, ghrun-if77zlumgq.auto.internal:1850): on response callback started
2025-12-04 21:04:22,230 - DEBUG - ydb.topic.2492558  - close: Topic client was closed
2025-12-04 21:04:22,230 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DeleteSession, c62ae488-f927-415f-83ee-9c861083b8ff, ghrun-if77zlumgq.auto.internal:1850): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:22,230 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:22,231 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint ghrun-if77zlumgq.auto.internal:1850
2025-12-04 21:04:22,231 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, c62ae488-f927-415f-83ee-9c861083b8ff, ghrun-if77zlumgq.auto.internal:1850): on response callback success
2025-12-04 21:04:22,232 - INFO - ydb.pool.Discovery.2492558  - run: Successfully terminated discovery process
2025-12-04 21:04:22,233 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 21:04:22,233 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - remove_database: /Root/users/database
2025-12-04 21:04:22,233 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 21:04:22,237 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764882262235677&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 21:04:22,238 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 21:04:22,741 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 21:04:22,741 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 21:04:22,744 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 21:04:23,745 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - stop: Stopped node localhost:1850/1
2025-12-04 21:04:23,745 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: done 0
2025-12-04 21:04:23,745 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 21:04:24,255 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:24,256 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25530
2025-12-04 21:04:24,256 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11854
2025-12-04 21:04:24,256 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15752
2025-12-04 21:04:24,257 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18087
2025-12-04 21:04:24,258 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12628
2025-12-04 21:04:24,258 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:24,258 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_1a8qiat2.log --grpc-port=25530 --mon-port=11854 --ic-port=15752
2025-12-04 21:04:24,258 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:24,259 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_1a8qiat2.log --grpc-port=25530 --mon-port=11854 --ic-port=15752
2025-12-04 21:04:24,259 - DEBUG - ya.test.2492558  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:28595', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_1a8qiat2.log', '--grpc-port=25530', '--mon-port=11854', '--ic-port=15752')' in '/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-12-04 21:04:24,260 - DEBUG - ya.test.2492558  - execute: Command pid: 2640564
2025-12-04 21:04:24,261 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Started node localhost:25530/2
2025-12-04 21:04:24,262 - DEBUG - ya.test.2492558  - _get_command_output_file: Command out will be placed to ydbd.out.41
2025-12-04 21:04:24,263 - DEBUG - ya.test.2492558  - _get_command_output_file: Command err will be placed to ydbd.err.41
2025-12-04 21:04:24,263 - DEBUG - ya.test.2492558  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:04:24,264 - DEBUG - ya.test.2492558  - execute: Command pid: 2640565
2025-12-04 21:04:24,587 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) rc: 0
2025-12-04 21:04:24,587 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) elapsed time (sec): 0.3220808506011963
2025-12-04 21:04:24,587 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) idrss: 0
2025-12-04 21:04:24,587 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) inblock: 0
2025-12-04 21:04:24,588 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) isrss: 0
2025-12-04 21:04:24,588 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) ixrss: 0
2025-12-04 21:04:24,588 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) majflt: 0
2025-12-04 21:04:24,588 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) maxrss: 546900
2025-12-04 21:04:24,588 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) minflt: 17033
2025-12-04 21:04:24,588 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) msgrcv: 0
2025-12-04 21:04:24,589 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) msgsnd: 0
2025-12-04 21:04:24,589 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) nivcsw: 1
2025-12-04 21:04:24,589 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) nsignals: 0
2025-12-04 21:04:24,589 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) nswap: 0
2025-12-04 21:04:24,589 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) nvcsw: 16
2025-12-04 21:04:24,589 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) oublock: 8
2025-12-04 21:04:24,589 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) stime: 0.185303
2025-12-04 21:04:24,590 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) utime: 0.088623
2025-12-04 21:04:24,590 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) wtime: 0.323
2025-12-04 21:04:24,590 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:04:24,590 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2640565) errors:

2025-12-04 21:04:24,590 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Node 2 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:04:24,590 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:24,592 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:24,592 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 21:04:24,592 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:24,593 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 21:04:24,596 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Subscribe waiter <Future at 0x7f61ce8137b0 state=pending>
2025-12-04 21:04:24,597 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 21:04:24,597 - DEBUG - ydb.table.SessionPool.2492558  - _create: Created session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:24,597 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:24,598 - ERROR - ydb.table.SessionPool.2492558  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 21:04:24,598 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 21:04:24,599 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ListEndpoints, 8a31c771-d193-464e-9457-387389ea0898, localhost:28595): creating call state
2025-12-04 21:04:24,599 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ListEndpoints, 8a31c771-d193-464e-9457-387389ea0898, localhost:28595): request = { database: "/Root/users/database" }
2025-12-04 21:04:24,601 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ListEndpoints, 8a31c771-d193-464e-9457-387389ea0898, localhost:28595): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-04 21:04:24,601 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:28595". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-04 21:04:24,602 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:26,535 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:26,536 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:26,539 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 21:04:26,540 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ListEndpoints, e136da92-c640-4ab4-96a2-22f772009097, localhost:28595): creating call state
2025-12-04 21:04:26,540 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ListEndpoints, e136da92-c640-4ab4-96a2-22f772009097, localhost:28595): request = { database: "/Root/users/database" }
2025-12-04 21:04:26,542 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ListEndpoints, e136da92-c640-4ab4-96a2-22f772009097, localhost:28595): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-if77zlumgq.auto.internal" port: 25530 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50001 } self_location: "1" } } } }
2025-12-04 21:04:26,543 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-if77zlumgq.auto.internal:25530, location , ssl: False>]>
2025-12-04 21:04:26,543 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:26,549 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:26,549 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,550 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateSession, 11cf9b44-4143-4209-8363-1ec191254830, ghrun-if77zlumgq.auto.internal:25530): creating call state
2025-12-04 21:04:26,551 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateSession, 11cf9b44-4143-4209-8363-1ec191254830, ghrun-if77zlumgq.auto.internal:25530): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:26,553 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, 11cf9b44-4143-4209-8363-1ec191254830, ghrun-if77zlumgq.auto.internal:25530): on response callback started
2025-12-04 21:04:26,554 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateSession, 11cf9b44-4143-4209-8363-1ec191254830, ghrun-if77zlumgq.auto.internal:25530): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=YzU2MmExNjEtYTU2OTAwMGMtMmJkNTYxMzMtOTk0MGJlMDM=" } } } }
2025-12-04 21:04:26,554 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,554 - DEBUG - ydb.table.SessionPool.2492558  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,555 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, 11cf9b44-4143-4209-8363-1ec191254830, ghrun-if77zlumgq.auto.internal:25530): on response callback success
2025-12-04 21:04:26,555 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 94f9efa9-bdba-4393-ac67-fcef6b94c2d8, ghrun-if77zlumgq.auto.internal:25530): creating call state
2025-12-04 21:04:26,556 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 94f9efa9-bdba-4393-ac67-fcef6b94c2d8, ghrun-if77zlumgq.auto.internal:25530): request = { session_id: "ydb://session/3?node_id=50001&id=YzU2MmExNjEtYTU2OTAwMGMtMmJkNTYxMzMtOTk0MGJlMDM=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:26,589 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 94f9efa9-bdba-4393-ac67-fcef6b94c2d8, ghrun-if77zlumgq.auto.internal:25530): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:26,589 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,590 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 21:04:26,591 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 8606d4a3-b7dd-42b6-8db3-1c104270bbbc, ghrun-if77zlumgq.auto.internal:25530): creating call state
2025-12-04 21:04:26,591 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 8606d4a3-b7dd-42b6-8db3-1c104270bbbc, ghrun-if77zlumgq.auto.internal:25530): request = { session_id: "ydb://session/3?node_id=50001&id=YzU2MmExNjEtYTU2OTAwMGMtMmJkNTYxMzMtOTk0MGJlMDM=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:26,613 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 8606d4a3-b7dd-42b6-8db3-1c104270bbbc, ghrun-if77zlumgq.auto.internal:25530): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:26,614 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,614 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 21:04:26,614 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, 2f25c059-2e5e-45ad-ad6a-8bd9920c4716, ghrun-if77zlumgq.auto.internal:25530): creating call state
2025-12-04 21:04:26,615 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, 2f25c059-2e5e-45ad-ad6a-8bd9920c4716, ghrun-if77zlumgq.auto.internal:25530): request = { session_id: "ydb://session/3?node_id=50001&id=YzU2MmExNjEtYTU2OTAwMGMtMmJkNTYxMzMtOTk0MGJlMDM=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_1\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_1\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:26,646 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, 2f25c059-2e5e-45ad-ad6a-8bd9920c4716, ghrun-if77zlumgq.auto.internal:25530): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 21:04:26,647 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,647 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 21:04:26,648 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, ca66e2f9-ceaf-4877-8fa6-afe76a0b7023, ghrun-if77zlumgq.auto.internal:25530): creating call state
2025-12-04 21:04:26,648 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, ca66e2f9-ceaf-4877-8fa6-afe76a0b7023, ghrun-if77zlumgq.auto.internal:25530): request = { session_id: "ydb://session/3?node_id=50001&id=YzU2MmExNjEtYTU2OTAwMGMtMmJkNTYxMzMtOTk0MGJlMDM=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:26,679 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, ca66e2f9-ceaf-4877-8fa6-afe76a0b7023, ghrun-if77zlumgq.auto.internal:25530): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-04 21:04:26,680 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,681 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 21:04:26,681 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DropTable, a48c6d33-8ed0-4a52-a9a7-4881433f85ea, ghrun-if77zlumgq.auto.internal:25530): creating call state
2025-12-04 21:04:26,682 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DropTable, a48c6d33-8ed0-4a52-a9a7-4881433f85ea, ghrun-if77zlumgq.auto.internal:25530): request = { session_id: "ydb://session/3?node_id=50001&id=YzU2MmExNjEtYTU2OTAwMGMtMmJkNTYxMzMtOTk0MGJlMDM=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:26,696 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DropTable, a48c6d33-8ed0-4a52-a9a7-4881433f85ea, ghrun-if77zlumgq.auto.internal:25530): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:26,696 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,696 - DEBUG - ydb.table.SessionPool.2492558  - stop: Requested session pool stop.
2025-12-04 21:04:26,696 - DEBUG - ydb.table.SessionPool.2492558  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 21:04:26,696 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroying sessions in active queue
2025-12-04 21:04:26,697 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f61ce811410>, reason: session-pool-terminated
2025-12-04 21:04:26,697 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Session <ydb.table.Session object at 0x7f61ce811410> is no longer active. Current active count 0.
2025-12-04 21:04:26,697 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DeleteSession, 3347b8b9-104b-4dea-805f-0c281b3c50d0, ghrun-if77zlumgq.auto.internal:25530): creating call state
2025-12-04 21:04:26,697 - DEBUG - ydb.table.SessionPool.2492558  - _terminate_event: Terminated session pool.
2025-12-04 21:04:26,698 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DeleteSession, 3347b8b9-104b-4dea-805f-0c281b3c50d0, ghrun-if77zlumgq.auto.internal:25530): request = { session_id: "ydb://session/3?node_id=50001&id=YzU2MmExNjEtYTU2OTAwMGMtMmJkNTYxMzMtOTk0MGJlMDM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:26,699 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f61ce811410>
2025-12-04 21:04:26,699 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroyed active sessions
2025-12-04 21:04:26,699 - DEBUG - ydb.topic.2492558  - close: Close topic client
2025-12-04 21:04:26,699 - DEBUG - ydb.topic.2492558  - close: Topic client was closed
2025-12-04 21:04:26,700 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:26,700 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, 3347b8b9-104b-4dea-805f-0c281b3c50d0, ghrun-if77zlumgq.auto.internal:25530): on response callback started
2025-12-04 21:04:26,700 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DeleteSession, 3347b8b9-104b-4dea-805f-0c281b3c50d0, ghrun-if77zlumgq.auto.internal:25530): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:26,701 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, 3347b8b9-104b-4dea-805f-0c281b3c50d0, ghrun-if77zlumgq.auto.internal:25530): on response callback success
2025-12-04 21:04:26,742 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint ghrun-if77zlumgq.auto.internal:25530
2025-12-04 21:04:26,752 - INFO - ydb.pool.Discovery.2492558  - run: Successfully terminated discovery process
2025-12-04 21:04:26,753 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 21:04:26,753 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - remove_database: /Root/users/database
2025-12-04 21:04:26,753 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 21:04:26,758 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764882266755661&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 21:04:26,758 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 21:04:27,262 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 21:04:27,262 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 21:04:27,265 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 21:04:28,266 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - stop: Stopped node localhost:25530/2
2025-12-04 21:04:28,266 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: done 1
2025-12-04 21:04:28,266 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 21:04:28,777 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:28,778 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28024
2025-12-04 21:04:28,778 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4647
2025-12-04 21:04:28,778 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4648
2025-12-04 21:04:28,779 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23979
2025-12-04 21:04:28,779 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7540
2025-12-04 21:04:28,779 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10741
2025-12-04 21:04:28,780 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:28,780 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_api7sacl.log --grpc-port=28024 --mon-port=4648 --ic-port=23979
2025-12-04 21:04:28,780 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:28,780 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_api7sacl.log --grpc-port=28024 --mon-port=4648 --ic-port=23979
2025-12-04 21:04:28,781 - DEBUG - ya.test.2492558  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:28595', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_api7sacl.log', '--grpc-port=28024', '--mon-port=4648', '--ic-port=23979')' in '/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-12-04 21:04:28,782 - DEBUG - ya.test.2492558  - execute: Command pid: 2643197
2025-12-04 21:04:28,783 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Started node localhost:28024/3
2025-12-04 21:04:28,784 - DEBUG - ya.test.2492558  - _get_command_output_file: Command out will be placed to ydbd.out.42
2025-12-04 21:04:28,785 - DEBUG - ya.test.2492558  - _get_command_output_file: Command err will be placed to ydbd.err.42
2025-12-04 21:04:28,787 - DEBUG - ya.test.2492558  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:04:28,787 - DEBUG - ya.test.2492558  - execute: Command pid: 2643198
2025-12-04 21:04:29,418 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) rc: 0
2025-12-04 21:04:29,419 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) elapsed time (sec): 0.6288902759552002
2025-12-04 21:04:29,419 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) idrss: 0
2025-12-04 21:04:29,419 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) inblock: 0
2025-12-04 21:04:29,419 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) isrss: 0
2025-12-04 21:04:29,419 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) ixrss: 0
2025-12-04 21:04:29,419 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) majflt: 1
2025-12-04 21:04:29,420 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) maxrss: 546988
2025-12-04 21:04:29,420 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) minflt: 17270
2025-12-04 21:04:29,420 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) msgrcv: 0
2025-12-04 21:04:29,420 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) msgsnd: 0
2025-12-04 21:04:29,420 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) nivcsw: 5
2025-12-04 21:04:29,420 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) nsignals: 0
2025-12-04 21:04:29,421 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) nswap: 0
2025-12-04 21:04:29,421 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) nvcsw: 26
2025-12-04 21:04:29,421 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) oublock: 8
2025-12-04 21:04:29,421 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) stime: 0.470147
2025-12-04 21:04:29,421 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) utime: 0.111562
2025-12-04 21:04:29,421 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) wtime: 0.631
2025-12-04 21:04:29,421 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:04:29,422 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2643198) errors:

2025-12-04 21:04:29,422 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Node 3 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:04:29,422 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:29,424 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:29,425 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:29,429 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 21:04:29,430 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 21:04:29,430 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Subscribe waiter <Future at 0x7f61d24df8f0 state=pending>
2025-12-04 21:04:29,431 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 21:04:29,431 - DEBUG - ydb.table.SessionPool.2492558  - _create: Created session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:29,431 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:29,431 - ERROR - ydb.table.SessionPool.2492558  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 21:04:29,433 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 21:04:29,433 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ListEndpoints, 0d8cfec6-741e-43de-9cc6-bcc038b8ac93, localhost:28595): creating call state
2025-12-04 21:04:29,434 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ListEndpoints, 0d8cfec6-741e-43de-9cc6-bcc038b8ac93, localhost:28595): request = { database: "/Root/users/database" }
2025-12-04 21:04:29,436 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ListEndpoints, 0d8cfec6-741e-43de-9cc6-bcc038b8ac93, localhost:28595): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { self_location: "1" } } } }
2025-12-04 21:04:29,436 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints []>
2025-12-04 21:04:29,436 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:30,764 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:30,765 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:30,770 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 21:04:30,770 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ListEndpoints, 5f349d9c-9696-4bc7-98a8-eec047fee3ef, localhost:28595): creating call state
2025-12-04 21:04:30,770 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ListEndpoints, 5f349d9c-9696-4bc7-98a8-eec047fee3ef, localhost:28595): request = { database: "/Root/users/database" }
2025-12-04 21:04:30,773 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ListEndpoints, 5f349d9c-9696-4bc7-98a8-eec047fee3ef, localhost:28595): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-if77zlumgq.auto.internal" port: 28024 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50002 } self_location: "1" } } } }
2025-12-04 21:04:30,773 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-if77zlumgq.auto.internal:28024, location , ssl: False>]>
2025-12-04 21:04:30,774 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:30,780 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:30,780 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:30,782 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateSession, e7250cf0-20a2-4495-b134-97a8ee19a897, ghrun-if77zlumgq.auto.internal:28024): creating call state
2025-12-04 21:04:30,782 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateSession, e7250cf0-20a2-4495-b134-97a8ee19a897, ghrun-if77zlumgq.auto.internal:28024): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:30,785 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, e7250cf0-20a2-4495-b134-97a8ee19a897, ghrun-if77zlumgq.auto.internal:28024): on response callback started
2025-12-04 21:04:30,786 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateSession, e7250cf0-20a2-4495-b134-97a8ee19a897, ghrun-if77zlumgq.auto.internal:28024): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=YmQwZDhhNDktM2EyOGRhMmQtNzFkZWQ3NTUtYzNkZTQ0ZDc=" } } } }
2025-12-04 21:04:30,786 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:30,786 - DEBUG - ydb.table.SessionPool.2492558  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:30,787 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, e7250cf0-20a2-4495-b134-97a8ee19a897, ghrun-if77zlumgq.auto.internal:28024): on response callback success
2025-12-04 21:04:30,787 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 592dee7e-a895-4b08-917e-d94c1a3d86c1, ghrun-if77zlumgq.auto.internal:28024): creating call state
2025-12-04 21:04:30,788 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 592dee7e-a895-4b08-917e-d94c1a3d86c1, ghrun-if77zlumgq.auto.internal:28024): request = { session_id: "ydb://session/3?node_id=50002&id=YmQwZDhhNDktM2EyOGRhMmQtNzFkZWQ3NTUtYzNkZTQ0ZDc=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:30,819 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 592dee7e-a895-4b08-917e-d94c1a3d86c1, ghrun-if77zlumgq.auto.internal:28024): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:30,819 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:30,820 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 21:04:30,820 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 27763e22-0b0f-4f3c-8056-139839fba326, ghrun-if77zlumgq.auto.internal:28024): creating call state
2025-12-04 21:04:30,821 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 27763e22-0b0f-4f3c-8056-139839fba326, ghrun-if77zlumgq.auto.internal:28024): request = { session_id: "ydb://session/3?node_id=50002&id=YmQwZDhhNDktM2EyOGRhMmQtNzFkZWQ3NTUtYzNkZTQ0ZDc=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:30,845 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 27763e22-0b0f-4f3c-8056-139839fba326, ghrun-if77zlumgq.auto.internal:28024): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:30,845 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:30,846 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 21:04:30,846 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, 8626f3d1-1a50-4c17-85e5-dcef6d8a954a, ghrun-if77zlumgq.auto.internal:28024): creating call state
2025-12-04 21:04:30,847 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, 8626f3d1-1a50-4c17-85e5-dcef6d8a954a, ghrun-if77zlumgq.auto.internal:28024): request = { session_id: "ydb://session/3?node_id=50002&id=YmQwZDhhNDktM2EyOGRhMmQtNzFkZWQ3NTUtYzNkZTQ0ZDc=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_2\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_2\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:30,990 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, 8626f3d1-1a50-4c17-85e5-dcef6d8a954a, ghrun-if77zlumgq.auto.internal:28024): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 21:04:30,991 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:30,991 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 21:04:30,992 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, 6f112881-7e7c-4398-8a00-a3c9fb3d1ec1, ghrun-if77zlumgq.auto.internal:28024): creating call state
2025-12-04 21:04:30,993 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, 6f112881-7e7c-4398-8a00-a3c9fb3d1ec1, ghrun-if77zlumgq.auto.internal:28024): request = { session_id: "ydb://session/3?node_id=50002&id=YmQwZDhhNDktM2EyOGRhMmQtNzFkZWQ3NTUtYzNkZTQ0ZDc=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:31,023 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, 6f112881-7e7c-4398-8a00-a3c9fb3d1ec1, ghrun-if77zlumgq.auto.internal:28024): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-04 21:04:31,024 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:31,024 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 21:04:31,025 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DropTable, f9b7ab24-4efa-4420-a09f-87dbc128a38e, ghrun-if77zlumgq.auto.internal:28024): creating call state
2025-12-04 21:04:31,025 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DropTable, f9b7ab24-4efa-4420-a09f-87dbc128a38e, ghrun-if77zlumgq.auto.internal:28024): request = { session_id: "ydb://session/3?node_id=50002&id=YmQwZDhhNDktM2EyOGRhMmQtNzFkZWQ3NTUtYzNkZTQ0ZDc=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:31,040 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DropTable, f9b7ab24-4efa-4420-a09f-87dbc128a38e, ghrun-if77zlumgq.auto.internal:28024): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:31,041 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:31,041 - DEBUG - ydb.table.SessionPool.2492558  - stop: Requested session pool stop.
2025-12-04 21:04:31,041 - DEBUG - ydb.table.SessionPool.2492558  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 21:04:31,042 - DEBUG - ydb.table.SessionPool.2492558  - _terminate_event: Terminated session pool.
2025-12-04 21:04:31,042 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroying sessions in active queue
2025-12-04 21:04:31,043 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f61d24df760>, reason: session-pool-terminated
2025-12-04 21:04:31,044 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Session <ydb.table.Session object at 0x7f61d24df760> is no longer active. Current active count 0.
2025-12-04 21:04:31,044 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DeleteSession, 52f6f482-3f44-4f9a-86b7-3ed3eeeb0044, ghrun-if77zlumgq.auto.internal:28024): creating call state
2025-12-04 21:04:31,045 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DeleteSession, 52f6f482-3f44-4f9a-86b7-3ed3eeeb0044, ghrun-if77zlumgq.auto.internal:28024): request = { session_id: "ydb://session/3?node_id=50002&id=YmQwZDhhNDktM2EyOGRhMmQtNzFkZWQ3NTUtYzNkZTQ0ZDc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:31,046 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f61d24df760>
2025-12-04 21:04:31,046 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroyed active sessions
2025-12-04 21:04:31,046 - DEBUG - ydb.topic.2492558  - close: Close topic client
2025-12-04 21:04:31,047 - DEBUG - ydb.topic.2492558  - close: Topic client was closed
2025-12-04 21:04:31,047 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, 52f6f482-3f44-4f9a-86b7-3ed3eeeb0044, ghrun-if77zlumgq.auto.internal:28024): on response callback started
2025-12-04 21:04:31,047 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:31,048 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint ghrun-if77zlumgq.auto.internal:28024
2025-12-04 21:04:31,048 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DeleteSession, 52f6f482-3f44-4f9a-86b7-3ed3eeeb0044, ghrun-if77zlumgq.auto.internal:28024): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:31,049 - INFO - ydb.pool.Discovery.2492558  - run: Successfully terminated discovery process
2025-12-04 21:04:31,049 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, 52f6f482-3f44-4f9a-86b7-3ed3eeeb0044, ghrun-if77zlumgq.auto.internal:28024): on response callback success
2025-12-04 21:04:31,051 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 21:04:31,051 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - remove_database: /Root/users/database
2025-12-04 21:04:31,052 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 21:04:31,056 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764882271053382&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 21:04:31,057 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 21:04:31,560 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 21:04:31,560 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 21:04:31,563 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 21:04:34,564 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - stop: Stopped node localhost:28024/3
2025-12-04 21:04:34,564 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: done 2
2025-12-04 21:04:34,564 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create_database
2025-12-04 21:04:35,074 - DEBUG - ya.test.2492558  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd
2025-12-04 21:04:35,075 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22332
2025-12-04 21:04:35,075 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12353
2025-12-04 21:04:35,075 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12502
2025-12-04 21:04:35,075 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9001
2025-12-04 21:04:35,076 - DEBUG - library.python.filelock.2492558  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64378
2025-12-04 21:04:35,076 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:35,076 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_b8e63f_n.log --grpc-port=22332 --mon-port=12353 --ic-port=12502
2025-12-04 21:04:35,077 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:04:35,077 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:28595 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_b8e63f_n.log --grpc-port=22332 --mon-port=12353 --ic-port=12502
2025-12-04 21:04:35,078 - DEBUG - ya.test.2492558  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:28595', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_b8e63f_n.log', '--grpc-port=22332', '--mon-port=12353', '--ic-port=12502')' in '/home/runner/.ya/build/build_root/6mdl/004faa/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_the_same_tenant2.enable_alter_database_create_hive_first--true/cluster/slot_4' (no wait)
2025-12-04 21:04:35,079 - DEBUG - ya.test.2492558  - execute: Command pid: 2646500
2025-12-04 21:04:35,079 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Started node localhost:22332/4
2025-12-04 21:04:35,081 - DEBUG - ya.test.2492558  - _get_command_output_file: Command out will be placed to ydbd.out.43
2025-12-04 21:04:35,082 - DEBUG - ya.test.2492558  - _get_command_output_file: Command err will be placed to ydbd.err.43
2025-12-04 21:04:35,082 - DEBUG - ya.test.2492558  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004faa/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:04:35,082 - DEBUG - ya.test.2492558  - execute: Command pid: 2646506
2025-12-04 21:04:37,723 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) rc: 0
2025-12-04 21:04:37,724 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) elapsed time (sec): 2.64044189453125
2025-12-04 21:04:37,724 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) idrss: 0
2025-12-04 21:04:37,724 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) inblock: 0
2025-12-04 21:04:37,724 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) isrss: 0
2025-12-04 21:04:37,724 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) ixrss: 0
2025-12-04 21:04:37,724 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) majflt: 0
2025-12-04 21:04:37,724 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) maxrss: 546852
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) minflt: 16266
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) msgrcv: 0
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) msgsnd: 0
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) nivcsw: 21
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) nsignals: 0
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) nswap: 0
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) nvcsw: 24
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) oublock: 8
2025-12-04 21:04:37,725 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) stime: 2.490123
2025-12-04 21:04:37,726 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) utime: 0.100329
2025-12-04 21:04:37,726 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) wtime: 2.641
2025-12-04 21:04:37,726 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:04:37,726 - DEBUG - ya.test.2492558  - _save_outputs: Command (pid 2646506) errors:

2025-12-04 21:04:37,726 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - start: Node 4 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:04:37,726 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:37,728 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:37,729 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:37,729 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table one
2025-12-04 21:04:37,732 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 21:04:37,732 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Subscribe waiter <Future at 0x7f61ce733710 state=pending>
2025-12-04 21:04:37,733 - DEBUG - ydb.table.SessionPool.2492558  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-04 21:04:37,733 - DEBUG - ydb.table.SessionPool.2492558  - _create: Created session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,734 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,735 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-04 21:04:37,736 - ERROR - ydb.table.SessionPool.2492558  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 21:04:37,736 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ListEndpoints, 18bb1e6d-6b58-4e2d-b4b2-22c978f7de7c, localhost:28595): creating call state
2025-12-04 21:04:37,737 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ListEndpoints, 18bb1e6d-6b58-4e2d-b4b2-22c978f7de7c, localhost:28595): request = { database: "/Root/users/database" }
2025-12-04 21:04:37,741 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ListEndpoints, 18bb1e6d-6b58-4e2d-b4b2-22c978f7de7c, localhost:28595): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-if77zlumgq.auto.internal" port: 22332 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50003 } self_location: "1" } } } }
2025-12-04 21:04:37,741 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2492558  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-if77zlumgq.auto.internal:22332, location , ssl: False>]>
2025-12-04 21:04:37,742 - DEBUG - ydb.connection.2492558  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:04:37,749 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:37,749 - DEBUG - ydb.table.SessionPool.2492558  - _prepare: Preparing session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,750 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateSession, fe225d3c-fbfc-42bd-8249-bebc8d64eadf, ghrun-if77zlumgq.auto.internal:22332): creating call state
2025-12-04 21:04:37,750 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateSession, fe225d3c-fbfc-42bd-8249-bebc8d64eadf, ghrun-if77zlumgq.auto.internal:22332): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:37,753 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, fe225d3c-fbfc-42bd-8249-bebc8d64eadf, ghrun-if77zlumgq.auto.internal:22332): on response callback started
2025-12-04 21:04:37,753 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateSession, fe225d3c-fbfc-42bd-8249-bebc8d64eadf, ghrun-if77zlumgq.auto.internal:22332): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=NWU0N2U4OTctMzViOTdmNzAtNGQzZjYyOWUtYzMxZjRhM2I=" } } } }
2025-12-04 21:04:37,753 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,754 - DEBUG - ydb.table.SessionPool.2492558  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,754 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 7229cc55-a5f2-4f5b-9867-6cfdc6565919, ghrun-if77zlumgq.auto.internal:22332): creating call state
2025-12-04 21:04:37,754 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(CreateSession, fe225d3c-fbfc-42bd-8249-bebc8d64eadf, ghrun-if77zlumgq.auto.internal:22332): on response callback success
2025-12-04 21:04:37,755 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 7229cc55-a5f2-4f5b-9867-6cfdc6565919, ghrun-if77zlumgq.auto.internal:22332): request = { session_id: "ydb://session/3?node_id=50003&id=NWU0N2U4OTctMzViOTdmNzAtNGQzZjYyOWUtYzMxZjRhM2I=" path: "/Root/users/database/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:37,785 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 7229cc55-a5f2-4f5b-9867-6cfdc6565919, ghrun-if77zlumgq.auto.internal:22332): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:37,785 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,786 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: create table two
2025-12-04 21:04:37,786 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(CreateTable, 34c1ca48-3cc7-4bc7-83cd-27ce1be93763, ghrun-if77zlumgq.auto.internal:22332): creating call state
2025-12-04 21:04:37,787 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(CreateTable, 34c1ca48-3cc7-4bc7-83cd-27ce1be93763, ghrun-if77zlumgq.auto.internal:22332): request = { session_id: "ydb://session/3?node_id=50003&id=NWU0N2U4OTctMzViOTdmNzAtNGQzZjYyOWUtYzMxZjRhM2I=" path: "/Root/users/database/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:37,808 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(CreateTable, 34c1ca48-3cc7-4bc7-83cd-27ce1be93763, ghrun-if77zlumgq.auto.internal:22332): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:37,809 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,809 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-04 21:04:37,809 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, 03a1ef08-89f8-4413-8a25-e2bae7685d7e, ghrun-if77zlumgq.auto.internal:22332): creating call state
2025-12-04 21:04:37,810 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, 03a1ef08-89f8-4413-8a25-e2bae7685d7e, ghrun-if77zlumgq.auto.internal:22332): request = { session_id: "ydb://session/3?node_id=50003&id=NWU0N2U4OTctMzViOTdmNzAtNGQzZjYyOWUtYzMxZjRhM2I=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_3\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_3\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:37,844 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, 03a1ef08-89f8-4413-8a25-e2bae7685d7e, ghrun-if77zlumgq.auto.internal:22332): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-04 21:04:37,845 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,845 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-04 21:04:37,845 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(ExecuteDataQuery, 1616ce33-b5ae-441a-afeb-19327a7eb532, ghrun-if77zlumgq.auto.internal:22332): creating call state
2025-12-04 21:04:37,847 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(ExecuteDataQuery, 1616ce33-b5ae-441a-afeb-19327a7eb532, ghrun-if77zlumgq.auto.internal:22332): request = { session_id: "ydb://session/3?node_id=50003&id=NWU0N2U4OTctMzViOTdmNzAtNGQzZjYyOWUtYzMxZjRhM2I=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:37,889 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(ExecuteDataQuery, 1616ce33-b5ae-441a-afeb-19327a7eb532, ghrun-if77zlumgq.auto.internal:22332): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-12-04 21:04:37,890 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,890 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-04 21:04:37,891 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DropTable, 47cf0811-0c03-46d8-83d3-4546ce0e1838, ghrun-if77zlumgq.auto.internal:22332): creating call state
2025-12-04 21:04:37,891 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DropTable, 47cf0811-0c03-46d8-83d3-4546ce0e1838, ghrun-if77zlumgq.auto.internal:22332): request = { session_id: "ydb://session/3?node_id=50003&id=NWU0N2U4OTctMzViOTdmNzAtNGQzZjYyOWUtYzMxZjRhM2I=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:04:37,906 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DropTable, 47cf0811-0c03-46d8-83d3-4546ce0e1838, ghrun-if77zlumgq.auto.internal:22332): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:37,907 - DEBUG - ydb.table.SessionPool.2492558  - put: Put on session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,907 - DEBUG - ydb.table.SessionPool.2492558  - stop: Requested session pool stop.
2025-12-04 21:04:37,907 - DEBUG - ydb.table.SessionPool.2492558  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 21:04:37,908 - DEBUG - ydb.table.SessionPool.2492558  - _terminate_event: Terminated session pool.
2025-12-04 21:04:37,908 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroying sessions in active queue
2025-12-04 21:04:37,909 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f61ce733a80>, reason: session-pool-terminated
2025-12-04 21:04:37,909 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Session <ydb.table.Session object at 0x7f61ce733a80> is no longer active. Current active count 0.
2025-12-04 21:04:37,910 - DEBUG - ydb.connection.2492558  - _prepare_call: RpcState(DeleteSession, a40caac3-3285-4c9e-9067-dd9b8f7b3cf0, ghrun-if77zlumgq.auto.internal:22332): creating call state
2025-12-04 21:04:37,910 - DEBUG - ydb.connection.2492558  - _log_request: RpcState(DeleteSession, a40caac3-3285-4c9e-9067-dd9b8f7b3cf0, ghrun-if77zlumgq.auto.internal:22332): request = { session_id: "ydb://session/3?node_id=50003&id=NWU0N2U4OTctMzViOTdmNzAtNGQzZjYyOWUtYzMxZjRhM2I=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:04:37,911 - DEBUG - ydb.table.SessionPool.2492558  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f61ce733a80>
2025-12-04 21:04:37,912 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, a40caac3-3285-4c9e-9067-dd9b8f7b3cf0, ghrun-if77zlumgq.auto.internal:22332): on response callback started
2025-12-04 21:04:37,912 - DEBUG - ydb.table.SessionPool.2492558  - stop: Destroyed active sessions
2025-12-04 21:04:37,913 - DEBUG - ydb.connection.2492558  - _log_response: RpcState(DeleteSession, a40caac3-3285-4c9e-9067-dd9b8f7b3cf0, ghrun-if77zlumgq.auto.internal:22332): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:04:37,913 - DEBUG - ydb.topic.2492558  - close: Close topic client
2025-12-04 21:04:37,913 - DEBUG - ydb.connection.2492558  - _on_response_callback: RpcState(DeleteSession, a40caac3-3285-4c9e-9067-dd9b8f7b3cf0, ghrun-if77zlumgq.auto.internal:22332): on response callback success
2025-12-04 21:04:37,914 - DEBUG - ydb.topic.2492558  - close: Topic client was closed
2025-12-04 21:04:37,914 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint localhost:28595
2025-12-04 21:04:37,938 - DEBUG - ydb.connection.2492558  - close: Closing channel for endpoint ghrun-if77zlumgq.auto.internal:22332
2025-12-04 21:04:37,951 - INFO - ydb.pool.Discovery.2492558  - run: Successfully terminated discovery process
2025-12-04 21:04:37,953 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-04 21:04:37,954 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - remove_database: /Root/users/database
2025-12-04 21:04:37,955 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-04 21:04:37,960 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1764882277958182&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 21:04:37,960 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-04 21:04:38,463 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-04 21:04:38,464 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-04 21:04:38,466 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2492558  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-04 21:04:41,467 - INFO - ydb.tests.library.harness.kikimr_runner.2492558  - stop: Stopped node localhost:22332/4
2025-12-04 21:04:41,468 - DEBUG - __tests__.test_dynamic_tenants.2492558  - test_create_and_drop_the_same_tenant2: done 3
2025-12-04 21:04:41,469 - INFO - ya.test.2492558  - pytest_runtest_teardown: Test teardown
