2025-12-12 16:06:45,205 - INFO - ya.test.1470822  - pytest_runtest_setup: ####################################################################################################
2025-12-12 16:06:45,205 - INFO - ya.test.1470822  - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true]
2025-12-12 16:06:45,205 - INFO - ya.test.1470822  - pytest_runtest_setup: ####################################################################################################
2025-12-12 16:06:45,205 - INFO - ya.test.1470822  - pytest_runtest_setup: Test setup
2025-12-12 16:06:45,206 - INFO - ydb.tests.library.fixtures.1470822  - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-12-12 16:06:52,208 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - stop: Stopped node localhost:19689/1
2025-12-12 16:06:52,210 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31110
2025-12-12 16:06:52,211 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8250
2025-12-12 16:06:52,211 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10757
2025-12-12 16:06:52,211 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12369
2025-12-12 16:06:52,211 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3409
2025-12-12 16:06:52,212 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4340
2025-12-12 16:06:52,212 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24503
2025-12-12 16:06:52,212 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6747
2025-12-12 16:06:52,212 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21410
2025-12-12 16:06:52,212 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21695
2025-12-12 16:06:52,213 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2209
2025-12-12 16:06:52,213 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12246
2025-12-12 16:06:52,213 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4229
2025-12-12 16:06:52,213 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30893
2025-12-12 16:06:52,213 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24880
2025-12-12 16:06:52,213 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17233
2025-12-12 16:06:52,214 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12693
2025-12-12 16:06:52,214 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28972
2025-12-12 16:06:52,214 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3616
2025-12-12 16:06:52,214 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28152
2025-12-12 16:06:52,214 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29046
2025-12-12 16:06:52,219 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31732
2025-12-12 16:06:52,219 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17962
2025-12-12 16:06:52,219 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19689
2025-12-12 16:06:52,219 - DEBUG - library.python.filelock.1470822  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28717
2025-12-12 16:06:52,314 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:06:52,315 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16776
2025-12-12 16:06:52,316 - INFO - ydb.tests.library.fixtures.1470822  - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-12-12 16:06:52,316 - INFO - ydb.tests.library.fixtures.1470822  - ydb_cluster: setup ydb_cluster as local
2025-12-12 16:06:52,316 - DEBUG - ydb.tests.library.harness.kikimr_runner.1470822  - start: Working directory: /home/runner/.ya/build/build_root/dbm4/004c78/r3tmp/kikimr_cluster_pg8ejiam
2025-12-12 16:06:52,356 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:06:52,357 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6779
2025-12-12 16:06:52,357 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27636
2025-12-12 16:06:52,357 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29907
2025-12-12 16:06:52,358 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15695
2025-12-12 16:06:52,358 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:06:52,359 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/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/dbm4/004c78/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_zds_m2h0.log --grpc-port=6779 --mon-port=27636 --ic-port=16776
2025-12-12 16:06:52,359 - DEBUG - ydb.tests.library.harness.kikimr_runner.1470822  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:6779/1, disk_size 68719476736
2025-12-12 16:06:52,359 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:06:52,359 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/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/dbm4/004c78/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_zds_m2h0.log --grpc-port=6779 --mon-port=27636 --ic-port=16776
2025-12-12 16:06:52,360 - DEBUG - ya.test.1470822  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c78/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/dbm4/004c78/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_zds_m2h0.log', '--grpc-port=6779', '--mon-port=27636', '--ic-port=16776')' in '/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:06:52,362 - DEBUG - ya.test.1470822  - execute: Command pid: 1600078
2025-12-12 16:06:52,362 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Started node localhost:6779/1
2025-12-12 16:06:52,364 - DEBUG - ya.test.1470822  - _get_command_output_file: Command out will be placed to ydbd.out.37
2025-12-12 16:06:52,364 - DEBUG - ya.test.1470822  - _get_command_output_file: Command err will be placed to ydbd.err.37
2025-12-12 16:06:52,365 - DEBUG - ya.test.1470822  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:06:52,366 - DEBUG - ya.test.1470822  - execute: Command pid: 1600079
2025-12-12 16:06:52,832 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) rc: 0
2025-12-12 16:06:52,833 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) elapsed time (sec): 0.4407176971435547
2025-12-12 16:06:52,833 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) idrss: 0
2025-12-12 16:06:52,833 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) inblock: 0
2025-12-12 16:06:52,833 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) isrss: 0
2025-12-12 16:06:52,833 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) ixrss: 0
2025-12-12 16:06:52,833 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) majflt: 0
2025-12-12 16:06:52,834 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) maxrss: 547580
2025-12-12 16:06:52,834 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) minflt: 16269
2025-12-12 16:06:52,834 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) msgrcv: 0
2025-12-12 16:06:52,834 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) msgsnd: 0
2025-12-12 16:06:52,834 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) nivcsw: 43
2025-12-12 16:06:52,834 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) nsignals: 0
2025-12-12 16:06:52,835 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) nswap: 0
2025-12-12 16:06:52,835 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) nvcsw: 14
2025-12-12 16:06:52,835 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) oublock: 8
2025-12-12 16:06:52,835 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) stime: 0.287454
2025-12-12 16:06:52,835 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) utime: 0.095856
2025-12-12 16:06:52,835 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) wtime: 0.442
2025-12-12 16:06:52,836 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-12 16:06:52,836 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1600079) errors:

2025-12-12 16:06:52,836 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Node 1 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:06:54,852 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:06:54,852 - DEBUG - ydb.tests.library.harness.kikimr_runner.1470822  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6779', '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: 16776\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-12 16:06:54,854 - DEBUG - ya.test.1470822  - _get_command_output_file: Command out will be placed to ydbd.out.38
2025-12-12 16:06:54,856 - DEBUG - ya.test.1470822  - _get_command_output_file: Command err will be placed to ydbd.err.38
2025-12-12 16:06:54,856 - DEBUG - ya.test.1470822  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6779', '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: 16776\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-12 16:06:54,858 - DEBUG - ya.test.1470822  - execute: Command pid: 1601100
2025-12-12 16:06:55,294 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) rc: 0
2025-12-12 16:06:55,294 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) elapsed time (sec): 0.43449878692626953
2025-12-12 16:06:55,294 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) idrss: 0
2025-12-12 16:06:55,295 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) inblock: 0
2025-12-12 16:06:55,295 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) isrss: 0
2025-12-12 16:06:55,295 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) ixrss: 0
2025-12-12 16:06:55,295 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) majflt: 0
2025-12-12 16:06:55,295 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) maxrss: 556036
2025-12-12 16:06:55,295 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) minflt: 18411
2025-12-12 16:06:55,295 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) msgrcv: 0
2025-12-12 16:06:55,296 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) msgsnd: 0
2025-12-12 16:06:55,296 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) nivcsw: 42
2025-12-12 16:06:55,296 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) nsignals: 0
2025-12-12 16:06:55,296 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) nswap: 0
2025-12-12 16:06:55,296 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) nvcsw: 123
2025-12-12 16:06:55,296 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) oublock: 8
2025-12-12 16:06:55,296 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) stime: 0.28317
2025-12-12 16:06:55,297 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) utime: 0.100297
2025-12-12 16:06:55,297 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) wtime: 0.436
2025-12-12 16:06:55,297 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-12 16:06:55,297 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601100) errors:

2025-12-12 16:06:55,298 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:06:55,299 - DEBUG - ydb.tests.library.harness.kikimr_runner.1470822  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6779', '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-12 16:06:55,300 - DEBUG - ya.test.1470822  - _get_command_output_file: Command out will be placed to ydbd.out.39
2025-12-12 16:06:55,301 - DEBUG - ya.test.1470822  - _get_command_output_file: Command err will be placed to ydbd.err.39
2025-12-12 16:06:55,302 - DEBUG - ya.test.1470822  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6779', '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-12 16:06:55,303 - DEBUG - ya.test.1470822  - execute: Command pid: 1601310
2025-12-12 16:06:55,814 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) rc: 0
2025-12-12 16:06:55,814 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) elapsed time (sec): 0.5101921558380127
2025-12-12 16:06:55,814 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) idrss: 0
2025-12-12 16:06:55,814 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) inblock: 0
2025-12-12 16:06:55,814 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) isrss: 0
2025-12-12 16:06:55,815 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) ixrss: 0
2025-12-12 16:06:55,815 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) majflt: 0
2025-12-12 16:06:55,815 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) maxrss: 556056
2025-12-12 16:06:55,815 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) minflt: 18413
2025-12-12 16:06:55,815 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) msgrcv: 0
2025-12-12 16:06:55,815 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) msgsnd: 0
2025-12-12 16:06:55,815 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) nivcsw: 82
2025-12-12 16:06:55,816 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) nsignals: 0
2025-12-12 16:06:55,816 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) nswap: 0
2025-12-12 16:06:55,816 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) nvcsw: 115
2025-12-12 16:06:55,816 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) oublock: 8
2025-12-12 16:06:55,816 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) stime: 0.327571
2025-12-12 16:06:55,816 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) utime: 0.12425399999999999
2025-12-12 16:06:55,816 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) wtime: 0.512
2025-12-12 16:06:55,817 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-12 16:06:55,817 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1601310) errors:

2025-12-12 16:06:55,817 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-12 16:06:55,860 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __run: Cluster started and initialized
2025-12-12 16:06:55,872 - INFO - ya.test.1470822  - 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-12 16:06:55,873 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create_database
2025-12-12 16:06:56,385 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:06:56,386 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10944
2025-12-12 16:06:56,387 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28869
2025-12-12 16:06:56,387 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3917
2025-12-12 16:06:56,387 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21025
2025-12-12 16:06:56,388 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28339
2025-12-12 16:06:56,389 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:06:56,390 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_jz88kunk.log --grpc-port=10944 --mon-port=28869 --ic-port=3917
2025-12-12 16:06:56,390 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:06:56,390 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_jz88kunk.log --grpc-port=10944 --mon-port=28869 --ic-port=3917
2025-12-12 16:06:56,391 - DEBUG - ya.test.1470822  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c78/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:6779', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_jz88kunk.log', '--grpc-port=10944', '--mon-port=28869', '--ic-port=3917')' in '/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:06:56,393 - DEBUG - ya.test.1470822  - execute: Command pid: 1602199
2025-12-12 16:06:56,393 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Started node localhost:10944/1
2025-12-12 16:06:56,395 - DEBUG - ya.test.1470822  - _get_command_output_file: Command out will be placed to ydbd.out.40
2025-12-12 16:06:56,396 - DEBUG - ya.test.1470822  - _get_command_output_file: Command err will be placed to ydbd.err.40
2025-12-12 16:06:56,396 - DEBUG - ya.test.1470822  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:06:56,397 - DEBUG - ya.test.1470822  - execute: Command pid: 1602201
2025-12-12 16:06:56,807 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) rc: 0
2025-12-12 16:06:56,807 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) elapsed time (sec): 0.4091949462890625
2025-12-12 16:06:56,807 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) idrss: 0
2025-12-12 16:06:56,807 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) inblock: 0
2025-12-12 16:06:56,808 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) isrss: 0
2025-12-12 16:06:56,808 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) ixrss: 0
2025-12-12 16:06:56,808 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) majflt: 0
2025-12-12 16:06:56,808 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) maxrss: 547580
2025-12-12 16:06:56,808 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) minflt: 16270
2025-12-12 16:06:56,808 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) msgrcv: 0
2025-12-12 16:06:56,809 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) msgsnd: 0
2025-12-12 16:06:56,809 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) nivcsw: 24
2025-12-12 16:06:56,809 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) nsignals: 0
2025-12-12 16:06:56,809 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) nswap: 0
2025-12-12 16:06:56,809 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) nvcsw: 14
2025-12-12 16:06:56,809 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) oublock: 8
2025-12-12 16:06:56,810 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) stime: 0.210369
2025-12-12 16:06:56,810 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) utime: 0.130984
2025-12-12 16:06:56,810 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) wtime: 0.41
2025-12-12 16:06:56,810 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-12 16:06:56,811 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1602201) errors:

2025-12-12 16:06:56,811 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Node 1 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:06:56,811 - DEBUG - ydb.connection.1470822  - 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-12 16:06:56,813 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:06:56,814 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table one
2025-12-12 16:06:56,815 - DEBUG - ydb.connection.1470822  - 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-12 16:06:56,815 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:06:56,818 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Subscribe waiter <Future at 0x7f0ead0cb4e0 state=pending>
2025-12-12 16:06:56,819 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-12 16:06:56,820 - DEBUG - ydb.table.SessionPool.1470822  - _create: Created session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:56,821 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:56,822 - ERROR - ydb.table.SessionPool.1470822  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:06:56,822 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:06:56,824 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, b0712723-ffb6-4523-a018-5e31c6146f18, localhost:6779): creating call state
2025-12-12 16:06:56,824 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, b0712723-ffb6-4523-a018-5e31c6146f18, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:06:56,827 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, b0712723-ffb6-4523-a018-5e31c6146f18, localhost:6779): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-12 16:06:56,827 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:6779". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-12 16:06:56,828 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:06:58,954 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:06:58,954 - DEBUG - ydb.connection.1470822  - 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-12 16:06:58,961 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:06:58,961 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, e6aa532e-2268-45ec-9c78-378f450c6f9d, localhost:6779): creating call state
2025-12-12 16:06:58,962 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, e6aa532e-2268-45ec-9c78-378f450c6f9d, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:06:58,970 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, e6aa532e-2268-45ec-9c78-378f450c6f9d, localhost:6779): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 10944 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-12 16:06:58,971 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jumawwbfgm.auto.internal:10944, location , ssl: False>]>
2025-12-12 16:06:58,972 - DEBUG - ydb.connection.1470822  - 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-12 16:06:58,980 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:06:58,980 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:58,981 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateSession, 659168ef-52a7-423e-9d2f-16a46c17406a, ghrun-jumawwbfgm.auto.internal:10944): creating call state
2025-12-12 16:06:58,982 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateSession, 659168ef-52a7-423e-9d2f-16a46c17406a, ghrun-jumawwbfgm.auto.internal:10944): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:06:58,986 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 659168ef-52a7-423e-9d2f-16a46c17406a, ghrun-jumawwbfgm.auto.internal:10944): on response callback started
2025-12-12 16:06:58,986 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateSession, 659168ef-52a7-423e-9d2f-16a46c17406a, ghrun-jumawwbfgm.auto.internal:10944): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YTJlZmJjZmUtM2MzZjM1NTQtMjk0NzA4Y2ItYWNlZGIzYTI=" } } } }
2025-12-12 16:06:58,987 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:58,988 - DEBUG - ydb.table.SessionPool.1470822  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:58,988 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 659168ef-52a7-423e-9d2f-16a46c17406a, ghrun-jumawwbfgm.auto.internal:10944): on response callback success
2025-12-12 16:06:58,988 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, 313e484c-cc0a-4dee-983c-c87e8542af36, ghrun-jumawwbfgm.auto.internal:10944): creating call state
2025-12-12 16:06:58,990 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, 313e484c-cc0a-4dee-983c-c87e8542af36, ghrun-jumawwbfgm.auto.internal:10944): request = { session_id: "ydb://session/3?node_id=50000&id=YTJlZmJjZmUtM2MzZjM1NTQtMjk0NzA4Y2ItYWNlZGIzYTI=" 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-12 16:06:59,056 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, 313e484c-cc0a-4dee-983c-c87e8542af36, ghrun-jumawwbfgm.auto.internal:10944): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:06:59,059 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:59,060 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table two
2025-12-12 16:06:59,061 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, d31ff092-91f9-496d-bb3b-b267c8002aa9, ghrun-jumawwbfgm.auto.internal:10944): creating call state
2025-12-12 16:06:59,061 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, d31ff092-91f9-496d-bb3b-b267c8002aa9, ghrun-jumawwbfgm.auto.internal:10944): request = { session_id: "ydb://session/3?node_id=50000&id=YTJlZmJjZmUtM2MzZjM1NTQtMjk0NzA4Y2ItYWNlZGIzYTI=" 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-12 16:06:59,110 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, d31ff092-91f9-496d-bb3b-b267c8002aa9, ghrun-jumawwbfgm.auto.internal:10944): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:06:59,111 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:59,111 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-12 16:06:59,112 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, feb8d12f-4e7a-473e-b6b9-ecaccda24f31, ghrun-jumawwbfgm.auto.internal:10944): creating call state
2025-12-12 16:06:59,112 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, feb8d12f-4e7a-473e-b6b9-ecaccda24f31, ghrun-jumawwbfgm.auto.internal:10944): request = { session_id: "ydb://session/3?node_id=50000&id=YTJlZmJjZmUtM2MzZjM1NTQtMjk0NzA4Y2ItYWNlZGIzYTI=" 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-12 16:06:59,141 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, feb8d12f-4e7a-473e-b6b9-ecaccda24f31, ghrun-jumawwbfgm.auto.internal:10944): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 16:06:59,142 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:59,142 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-12 16:06:59,143 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, 3dcd0e04-1c77-4428-b38f-7d39f425cbc4, ghrun-jumawwbfgm.auto.internal:10944): creating call state
2025-12-12 16:06:59,143 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, 3dcd0e04-1c77-4428-b38f-7d39f425cbc4, ghrun-jumawwbfgm.auto.internal:10944): request = { session_id: "ydb://session/3?node_id=50000&id=YTJlZmJjZmUtM2MzZjM1NTQtMjk0NzA4Y2ItYWNlZGIzYTI=" 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-12 16:06:59,187 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, 3dcd0e04-1c77-4428-b38f-7d39f425cbc4, ghrun-jumawwbfgm.auto.internal:10944): 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-12 16:06:59,188 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:59,188 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-12 16:06:59,188 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DropTable, f5d79779-d7d3-4afa-9c5e-b53e956d78fb, ghrun-jumawwbfgm.auto.internal:10944): creating call state
2025-12-12 16:06:59,189 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DropTable, f5d79779-d7d3-4afa-9c5e-b53e956d78fb, ghrun-jumawwbfgm.auto.internal:10944): request = { session_id: "ydb://session/3?node_id=50000&id=YTJlZmJjZmUtM2MzZjM1NTQtMjk0NzA4Y2ItYWNlZGIzYTI=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 16:06:59,222 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DropTable, f5d79779-d7d3-4afa-9c5e-b53e956d78fb, ghrun-jumawwbfgm.auto.internal:10944): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:06:59,223 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:59,223 - DEBUG - ydb.table.SessionPool.1470822  - stop: Requested session pool stop.
2025-12-12 16:06:59,223 - DEBUG - ydb.table.SessionPool.1470822  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:06:59,224 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroying sessions in active queue
2025-12-12 16:06:59,224 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0ead072ea0>, reason: session-pool-terminated
2025-12-12 16:06:59,224 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Session <ydb.table.Session object at 0x7f0ead072ea0> is no longer active. Current active count 0.
2025-12-12 16:06:59,224 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DeleteSession, 68042c40-1db6-429f-a1d1-3b7eacd34bbc, ghrun-jumawwbfgm.auto.internal:10944): creating call state
2025-12-12 16:06:59,225 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DeleteSession, 68042c40-1db6-429f-a1d1-3b7eacd34bbc, ghrun-jumawwbfgm.auto.internal:10944): request = { session_id: "ydb://session/3?node_id=50000&id=YTJlZmJjZmUtM2MzZjM1NTQtMjk0NzA4Y2ItYWNlZGIzYTI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:06:59,227 - DEBUG - ydb.table.SessionPool.1470822  - _terminate_event: Terminated session pool.
2025-12-12 16:06:59,227 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f0ead072ea0>
2025-12-12 16:06:59,228 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroyed active sessions
2025-12-12 16:06:59,228 - DEBUG - ydb.topic.1470822  - close: Close topic client
2025-12-12 16:06:59,228 - DEBUG - ydb.topic.1470822  - close: Topic client was closed
2025-12-12 16:06:59,228 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:06:59,229 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, 68042c40-1db6-429f-a1d1-3b7eacd34bbc, ghrun-jumawwbfgm.auto.internal:10944): on response callback started
2025-12-12 16:06:59,230 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DeleteSession, 68042c40-1db6-429f-a1d1-3b7eacd34bbc, ghrun-jumawwbfgm.auto.internal:10944): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:06:59,230 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, 68042c40-1db6-429f-a1d1-3b7eacd34bbc, ghrun-jumawwbfgm.auto.internal:10944): on response callback success
2025-12-12 16:06:59,231 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:10944
2025-12-12 16:06:59,232 - INFO - ydb.pool.Discovery.1470822  - run: Successfully terminated discovery process
2025-12-12 16:06:59,232 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-12 16:06:59,232 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - remove_database: /Root/users/database
2025-12-12 16:06:59,233 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-12 16:06:59,238 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765555619234942&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:06:59,238 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-12 16:06:59,743 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-12 16:06:59,743 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-12 16:06:59,747 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-12 16:07:00,750 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - stop: Stopped node localhost:10944/1
2025-12-12 16:07:00,751 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: done 0
2025-12-12 16:07:00,752 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create_database
2025-12-12 16:07:01,264 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:07:01,264 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8385
2025-12-12 16:07:01,265 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7899
2025-12-12 16:07:01,266 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16031
2025-12-12 16:07:01,266 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17209
2025-12-12 16:07:01,266 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25428
2025-12-12 16:07:01,267 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:01,267 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_exgvs941.log --grpc-port=8385 --mon-port=7899 --ic-port=16031
2025-12-12 16:07:01,268 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:01,268 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_exgvs941.log --grpc-port=8385 --mon-port=7899 --ic-port=16031
2025-12-12 16:07:01,269 - DEBUG - ya.test.1470822  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c78/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:6779', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_exgvs941.log', '--grpc-port=8385', '--mon-port=7899', '--ic-port=16031')' in '/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:01,273 - DEBUG - ya.test.1470822  - execute: Command pid: 1605654
2025-12-12 16:07:01,273 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Started node localhost:8385/2
2025-12-12 16:07:01,275 - DEBUG - ya.test.1470822  - _get_command_output_file: Command out will be placed to ydbd.out.41
2025-12-12 16:07:01,276 - DEBUG - ya.test.1470822  - _get_command_output_file: Command err will be placed to ydbd.err.41
2025-12-12 16:07:01,276 - DEBUG - ya.test.1470822  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:07:01,277 - DEBUG - ya.test.1470822  - execute: Command pid: 1605661
2025-12-12 16:07:01,817 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) rc: 0
2025-12-12 16:07:01,818 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) elapsed time (sec): 0.5391933917999268
2025-12-12 16:07:01,818 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) idrss: 0
2025-12-12 16:07:01,818 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) inblock: 0
2025-12-12 16:07:01,818 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) isrss: 0
2025-12-12 16:07:01,818 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) ixrss: 0
2025-12-12 16:07:01,819 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) majflt: 0
2025-12-12 16:07:01,819 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) maxrss: 547580
2025-12-12 16:07:01,819 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) minflt: 16271
2025-12-12 16:07:01,819 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) msgrcv: 0
2025-12-12 16:07:01,819 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) msgsnd: 0
2025-12-12 16:07:01,819 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) nivcsw: 87
2025-12-12 16:07:01,820 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) nsignals: 0
2025-12-12 16:07:01,820 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) nswap: 0
2025-12-12 16:07:01,820 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) nvcsw: 21
2025-12-12 16:07:01,820 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) oublock: 8
2025-12-12 16:07:01,820 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) stime: 0.276402
2025-12-12 16:07:01,820 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) utime: 0.107488
2025-12-12 16:07:01,821 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) wtime: 0.54
2025-12-12 16:07:01,821 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-12 16:07:01,821 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1605661) errors:

2025-12-12 16:07:01,821 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Node 2 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:07:01,822 - DEBUG - ydb.connection.1470822  - 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-12 16:07:01,824 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:01,825 - DEBUG - ydb.connection.1470822  - 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-12 16:07:01,827 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table one
2025-12-12 16:07:01,831 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:07:01,831 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Subscribe waiter <Future at 0x7f0eac6e8c40 state=pending>
2025-12-12 16:07:01,831 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-12 16:07:01,832 - DEBUG - ydb.table.SessionPool.1470822  - _create: Created session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:01,832 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:01,832 - ERROR - ydb.table.SessionPool.1470822  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:07:01,837 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:07:01,838 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, 289e52b5-88d1-4f63-8ab6-d11a30acb0b8, localhost:6779): creating call state
2025-12-12 16:07:01,838 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, 289e52b5-88d1-4f63-8ab6-d11a30acb0b8, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:07:01,844 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, 289e52b5-88d1-4f63-8ab6-d11a30acb0b8, localhost:6779): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { self_location: "1" } } } }
2025-12-12 16:07:01,845 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints []>
2025-12-12 16:07:01,845 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:03,174 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:03,175 - DEBUG - ydb.connection.1470822  - 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-12 16:07:03,181 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:07:03,181 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, 15b8b1ee-f3a2-4b27-bebc-fa905aa068a9, localhost:6779): creating call state
2025-12-12 16:07:03,182 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, 15b8b1ee-f3a2-4b27-bebc-fa905aa068a9, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:07:03,184 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, 15b8b1ee-f3a2-4b27-bebc-fa905aa068a9, localhost:6779): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 8385 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-12 16:07:03,185 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jumawwbfgm.auto.internal:8385, location , ssl: False>]>
2025-12-12 16:07:03,187 - DEBUG - ydb.connection.1470822  - 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-12 16:07:03,196 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:03,196 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,204 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateSession, 74703ed3-be1b-4eae-beee-cc33267665c7, ghrun-jumawwbfgm.auto.internal:8385): creating call state
2025-12-12 16:07:03,205 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateSession, 74703ed3-be1b-4eae-beee-cc33267665c7, ghrun-jumawwbfgm.auto.internal:8385): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:03,208 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 74703ed3-be1b-4eae-beee-cc33267665c7, ghrun-jumawwbfgm.auto.internal:8385): on response callback started
2025-12-12 16:07:03,209 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateSession, 74703ed3-be1b-4eae-beee-cc33267665c7, ghrun-jumawwbfgm.auto.internal:8385): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=ZWYzMzA1MDItYTgwYTczNWUtNDgyYTFkYzYtY2UxY2QwZjA=" } } } }
2025-12-12 16:07:03,209 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,209 - DEBUG - ydb.table.SessionPool.1470822  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,210 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 74703ed3-be1b-4eae-beee-cc33267665c7, ghrun-jumawwbfgm.auto.internal:8385): on response callback success
2025-12-12 16:07:03,210 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, 5c2d9f09-bbae-49fb-97bf-9580d7956b0e, ghrun-jumawwbfgm.auto.internal:8385): creating call state
2025-12-12 16:07:03,211 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, 5c2d9f09-bbae-49fb-97bf-9580d7956b0e, ghrun-jumawwbfgm.auto.internal:8385): request = { session_id: "ydb://session/3?node_id=50001&id=ZWYzMzA1MDItYTgwYTczNWUtNDgyYTFkYzYtY2UxY2QwZjA=" 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-12 16:07:03,252 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, 5c2d9f09-bbae-49fb-97bf-9580d7956b0e, ghrun-jumawwbfgm.auto.internal:8385): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:03,252 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,253 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table two
2025-12-12 16:07:03,253 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, 027d427a-0846-4f75-89b1-df638060c9b2, ghrun-jumawwbfgm.auto.internal:8385): creating call state
2025-12-12 16:07:03,254 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, 027d427a-0846-4f75-89b1-df638060c9b2, ghrun-jumawwbfgm.auto.internal:8385): request = { session_id: "ydb://session/3?node_id=50001&id=ZWYzMzA1MDItYTgwYTczNWUtNDgyYTFkYzYtY2UxY2QwZjA=" 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-12 16:07:03,283 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, 027d427a-0846-4f75-89b1-df638060c9b2, ghrun-jumawwbfgm.auto.internal:8385): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:03,284 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,284 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-12 16:07:03,284 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, fd61fc19-074b-4602-b3f3-0670eccdfad3, ghrun-jumawwbfgm.auto.internal:8385): creating call state
2025-12-12 16:07:03,285 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, fd61fc19-074b-4602-b3f3-0670eccdfad3, ghrun-jumawwbfgm.auto.internal:8385): request = { session_id: "ydb://session/3?node_id=50001&id=ZWYzMzA1MDItYTgwYTczNWUtNDgyYTFkYzYtY2UxY2QwZjA=" 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-12 16:07:03,456 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, fd61fc19-074b-4602-b3f3-0670eccdfad3, ghrun-jumawwbfgm.auto.internal:8385): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 16:07:03,457 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,457 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-12 16:07:03,458 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, 4517d1d5-72dd-4aa8-b7b3-6a65fe65169e, ghrun-jumawwbfgm.auto.internal:8385): creating call state
2025-12-12 16:07:03,458 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, 4517d1d5-72dd-4aa8-b7b3-6a65fe65169e, ghrun-jumawwbfgm.auto.internal:8385): request = { session_id: "ydb://session/3?node_id=50001&id=ZWYzMzA1MDItYTgwYTczNWUtNDgyYTFkYzYtY2UxY2QwZjA=" 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-12 16:07:03,495 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, 4517d1d5-72dd-4aa8-b7b3-6a65fe65169e, ghrun-jumawwbfgm.auto.internal:8385): 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-12 16:07:03,496 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,497 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-12 16:07:03,497 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DropTable, 982a4cc5-4814-4547-9b41-1da8d5d90ee6, ghrun-jumawwbfgm.auto.internal:8385): creating call state
2025-12-12 16:07:03,497 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DropTable, 982a4cc5-4814-4547-9b41-1da8d5d90ee6, ghrun-jumawwbfgm.auto.internal:8385): request = { session_id: "ydb://session/3?node_id=50001&id=ZWYzMzA1MDItYTgwYTczNWUtNDgyYTFkYzYtY2UxY2QwZjA=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 16:07:03,522 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DropTable, 982a4cc5-4814-4547-9b41-1da8d5d90ee6, ghrun-jumawwbfgm.auto.internal:8385): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:03,522 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,523 - DEBUG - ydb.table.SessionPool.1470822  - stop: Requested session pool stop.
2025-12-12 16:07:03,523 - DEBUG - ydb.table.SessionPool.1470822  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:07:03,523 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroying sessions in active queue
2025-12-12 16:07:03,523 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0eac6e96e0>, reason: session-pool-terminated
2025-12-12 16:07:03,523 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Session <ydb.table.Session object at 0x7f0eac6e96e0> is no longer active. Current active count 0.
2025-12-12 16:07:03,524 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DeleteSession, a07c4f43-9397-4214-9f46-d95bfcb3d5b2, ghrun-jumawwbfgm.auto.internal:8385): creating call state
2025-12-12 16:07:03,524 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DeleteSession, a07c4f43-9397-4214-9f46-d95bfcb3d5b2, ghrun-jumawwbfgm.auto.internal:8385): request = { session_id: "ydb://session/3?node_id=50001&id=ZWYzMzA1MDItYTgwYTczNWUtNDgyYTFkYzYtY2UxY2QwZjA=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:03,525 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f0eac6e96e0>
2025-12-12 16:07:03,526 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroyed active sessions
2025-12-12 16:07:03,526 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, a07c4f43-9397-4214-9f46-d95bfcb3d5b2, ghrun-jumawwbfgm.auto.internal:8385): on response callback started
2025-12-12 16:07:03,527 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DeleteSession, a07c4f43-9397-4214-9f46-d95bfcb3d5b2, ghrun-jumawwbfgm.auto.internal:8385): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:03,527 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, a07c4f43-9397-4214-9f46-d95bfcb3d5b2, ghrun-jumawwbfgm.auto.internal:8385): on response callback success
2025-12-12 16:07:03,527 - DEBUG - ydb.table.SessionPool.1470822  - _terminate_event: Terminated session pool.
2025-12-12 16:07:03,528 - DEBUG - ydb.topic.1470822  - close: Close topic client
2025-12-12 16:07:03,529 - DEBUG - ydb.topic.1470822  - close: Topic client was closed
2025-12-12 16:07:03,529 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:03,529 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:8385
2025-12-12 16:07:03,530 - INFO - ydb.pool.Discovery.1470822  - run: Successfully terminated discovery process
2025-12-12 16:07:03,530 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-12 16:07:03,530 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - remove_database: /Root/users/database
2025-12-12 16:07:03,531 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-12 16:07:03,535 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765555623532674&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:07:03,535 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-12 16:07:04,038 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-12 16:07:04,039 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-12 16:07:04,041 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-12 16:07:05,042 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - stop: Stopped node localhost:8385/2
2025-12-12 16:07:05,043 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: done 1
2025-12-12 16:07:05,043 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create_database
2025-12-12 16:07:05,559 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:07:05,560 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3353
2025-12-12 16:07:05,561 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24827
2025-12-12 16:07:05,562 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2286
2025-12-12 16:07:05,562 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23012
2025-12-12 16:07:05,563 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7979
2025-12-12 16:07:05,564 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:05,564 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_i227l4dm.log --grpc-port=3353 --mon-port=24827 --ic-port=2286
2025-12-12 16:07:05,564 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:05,565 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_i227l4dm.log --grpc-port=3353 --mon-port=24827 --ic-port=2286
2025-12-12 16:07:05,565 - DEBUG - ya.test.1470822  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c78/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:6779', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_i227l4dm.log', '--grpc-port=3353', '--mon-port=24827', '--ic-port=2286')' in '/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:05,568 - DEBUG - ya.test.1470822  - execute: Command pid: 1608920
2025-12-12 16:07:05,568 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Started node localhost:3353/3
2025-12-12 16:07:05,570 - DEBUG - ya.test.1470822  - _get_command_output_file: Command out will be placed to ydbd.out.42
2025-12-12 16:07:05,571 - DEBUG - ya.test.1470822  - _get_command_output_file: Command err will be placed to ydbd.err.42
2025-12-12 16:07:05,571 - DEBUG - ya.test.1470822  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:07:05,572 - DEBUG - ya.test.1470822  - execute: Command pid: 1608932
2025-12-12 16:07:05,970 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) rc: 0
2025-12-12 16:07:05,970 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) elapsed time (sec): 0.3969075679779053
2025-12-12 16:07:05,970 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) idrss: 0
2025-12-12 16:07:05,971 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) inblock: 0
2025-12-12 16:07:05,971 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) isrss: 0
2025-12-12 16:07:05,971 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) ixrss: 0
2025-12-12 16:07:05,971 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) majflt: 0
2025-12-12 16:07:05,971 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) maxrss: 547580
2025-12-12 16:07:05,972 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) minflt: 16268
2025-12-12 16:07:05,972 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) msgrcv: 0
2025-12-12 16:07:05,972 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) msgsnd: 0
2025-12-12 16:07:05,972 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) nivcsw: 22
2025-12-12 16:07:05,972 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) nsignals: 0
2025-12-12 16:07:05,972 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) nswap: 0
2025-12-12 16:07:05,973 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) nvcsw: 16
2025-12-12 16:07:05,973 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) oublock: 8
2025-12-12 16:07:05,973 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) stime: 0.24273999999999998
2025-12-12 16:07:05,973 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) utime: 0.103582
2025-12-12 16:07:05,973 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) wtime: 0.398
2025-12-12 16:07:05,973 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-12 16:07:05,973 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1608932) errors:

2025-12-12 16:07:05,974 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Node 3 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:07:05,974 - DEBUG - ydb.connection.1470822  - 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-12 16:07:05,976 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:05,977 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table one
2025-12-12 16:07:05,977 - DEBUG - ydb.connection.1470822  - 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-12 16:07:05,977 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:07:05,980 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Subscribe waiter <Future at 0x7f0eac6c16e0 state=pending>
2025-12-12 16:07:05,981 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-12 16:07:05,982 - DEBUG - ydb.table.SessionPool.1470822  - _create: Created session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:05,983 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:05,983 - ERROR - ydb.table.SessionPool.1470822  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:07:05,984 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:07:05,985 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, 08479cc4-4e5f-4e0f-a416-41d7affbf36e, localhost:6779): creating call state
2025-12-12 16:07:05,985 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, 08479cc4-4e5f-4e0f-a416-41d7affbf36e, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:07:05,987 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, 08479cc4-4e5f-4e0f-a416-41d7affbf36e, localhost:6779): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-12 16:07:05,987 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:6779". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-12 16:07:05,988 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:07,592 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:07,593 - DEBUG - ydb.connection.1470822  - 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-12 16:07:07,597 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:07:07,598 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, 26f988e0-7897-419e-919c-1d5685b1c5c5, localhost:6779): creating call state
2025-12-12 16:07:07,598 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, 26f988e0-7897-419e-919c-1d5685b1c5c5, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:07:07,601 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, 26f988e0-7897-419e-919c-1d5685b1c5c5, localhost:6779): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 3353 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-12 16:07:07,601 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jumawwbfgm.auto.internal:3353, location , ssl: False>]>
2025-12-12 16:07:07,601 - DEBUG - ydb.connection.1470822  - 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-12 16:07:07,610 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:07,610 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:07,611 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateSession, 51e01167-01a5-474d-81ff-973ef755e673, ghrun-jumawwbfgm.auto.internal:3353): creating call state
2025-12-12 16:07:07,612 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateSession, 51e01167-01a5-474d-81ff-973ef755e673, ghrun-jumawwbfgm.auto.internal:3353): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:07,614 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 51e01167-01a5-474d-81ff-973ef755e673, ghrun-jumawwbfgm.auto.internal:3353): on response callback started
2025-12-12 16:07:07,615 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateSession, 51e01167-01a5-474d-81ff-973ef755e673, ghrun-jumawwbfgm.auto.internal:3353): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=ZmMzMzAyMjgtMTgwZmMxY2YtNThiZmU2MjktMzVmMmMxODE=" } } } }
2025-12-12 16:07:07,615 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:07,615 - DEBUG - ydb.table.SessionPool.1470822  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:07,616 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, 3cc7dca6-d45c-4ee9-ab2e-a103eceece3c, ghrun-jumawwbfgm.auto.internal:3353): creating call state
2025-12-12 16:07:07,618 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, 3cc7dca6-d45c-4ee9-ab2e-a103eceece3c, ghrun-jumawwbfgm.auto.internal:3353): request = { session_id: "ydb://session/3?node_id=50002&id=ZmMzMzAyMjgtMTgwZmMxY2YtNThiZmU2MjktMzVmMmMxODE=" 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-12 16:07:07,616 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 51e01167-01a5-474d-81ff-973ef755e673, ghrun-jumawwbfgm.auto.internal:3353): on response callback success
2025-12-12 16:07:07,693 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, 3cc7dca6-d45c-4ee9-ab2e-a103eceece3c, ghrun-jumawwbfgm.auto.internal:3353): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:07,693 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:07,694 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table two
2025-12-12 16:07:07,695 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, e5fac699-4570-4d53-a092-6a1850be0347, ghrun-jumawwbfgm.auto.internal:3353): creating call state
2025-12-12 16:07:07,695 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, e5fac699-4570-4d53-a092-6a1850be0347, ghrun-jumawwbfgm.auto.internal:3353): request = { session_id: "ydb://session/3?node_id=50002&id=ZmMzMzAyMjgtMTgwZmMxY2YtNThiZmU2MjktMzVmMmMxODE=" 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-12 16:07:07,734 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, e5fac699-4570-4d53-a092-6a1850be0347, ghrun-jumawwbfgm.auto.internal:3353): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:07,735 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:07,735 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-12 16:07:07,736 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, ec68142c-a868-477d-ae6c-40e77fea1835, ghrun-jumawwbfgm.auto.internal:3353): creating call state
2025-12-12 16:07:07,736 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, ec68142c-a868-477d-ae6c-40e77fea1835, ghrun-jumawwbfgm.auto.internal:3353): request = { session_id: "ydb://session/3?node_id=50002&id=ZmMzMzAyMjgtMTgwZmMxY2YtNThiZmU2MjktMzVmMmMxODE=" 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-12 16:07:07,942 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, ec68142c-a868-477d-ae6c-40e77fea1835, ghrun-jumawwbfgm.auto.internal:3353): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 16:07:07,942 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:07,943 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-12 16:07:07,943 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, ca846b8c-1d54-40ca-aabc-a56da90c78d9, ghrun-jumawwbfgm.auto.internal:3353): creating call state
2025-12-12 16:07:07,944 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, ca846b8c-1d54-40ca-aabc-a56da90c78d9, ghrun-jumawwbfgm.auto.internal:3353): request = { session_id: "ydb://session/3?node_id=50002&id=ZmMzMzAyMjgtMTgwZmMxY2YtNThiZmU2MjktMzVmMmMxODE=" 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-12 16:07:07,982 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, ca846b8c-1d54-40ca-aabc-a56da90c78d9, ghrun-jumawwbfgm.auto.internal:3353): 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-12 16:07:07,983 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:07,985 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-12 16:07:07,985 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DropTable, 53a47a86-6054-4fcb-b44d-6364961b8b13, ghrun-jumawwbfgm.auto.internal:3353): creating call state
2025-12-12 16:07:07,986 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DropTable, 53a47a86-6054-4fcb-b44d-6364961b8b13, ghrun-jumawwbfgm.auto.internal:3353): request = { session_id: "ydb://session/3?node_id=50002&id=ZmMzMzAyMjgtMTgwZmMxY2YtNThiZmU2MjktMzVmMmMxODE=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 16:07:08,026 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DropTable, 53a47a86-6054-4fcb-b44d-6364961b8b13, ghrun-jumawwbfgm.auto.internal:3353): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:08,026 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:08,027 - DEBUG - ydb.table.SessionPool.1470822  - stop: Requested session pool stop.
2025-12-12 16:07:08,027 - DEBUG - ydb.table.SessionPool.1470822  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:07:08,027 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroying sessions in active queue
2025-12-12 16:07:08,027 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0eac6c3ad0>, reason: session-pool-terminated
2025-12-12 16:07:08,027 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Session <ydb.table.Session object at 0x7f0eac6c3ad0> is no longer active. Current active count 0.
2025-12-12 16:07:08,028 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DeleteSession, a21db400-a046-4607-a494-011475c676e5, ghrun-jumawwbfgm.auto.internal:3353): creating call state
2025-12-12 16:07:08,028 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DeleteSession, a21db400-a046-4607-a494-011475c676e5, ghrun-jumawwbfgm.auto.internal:3353): request = { session_id: "ydb://session/3?node_id=50002&id=ZmMzMzAyMjgtMTgwZmMxY2YtNThiZmU2MjktMzVmMmMxODE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:08,029 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f0eac6c3ad0>
2025-12-12 16:07:08,029 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroyed active sessions
2025-12-12 16:07:08,030 - DEBUG - ydb.table.SessionPool.1470822  - _terminate_event: Terminated session pool.
2025-12-12 16:07:08,032 - DEBUG - ydb.topic.1470822  - close: Close topic client
2025-12-12 16:07:08,035 - DEBUG - ydb.topic.1470822  - close: Topic client was closed
2025-12-12 16:07:08,039 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:08,039 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:3353
2025-12-12 16:07:08,040 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, a21db400-a046-4607-a494-011475c676e5, ghrun-jumawwbfgm.auto.internal:3353): on response callback started
2025-12-12 16:07:08,054 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DeleteSession, a21db400-a046-4607-a494-011475c676e5, ghrun-jumawwbfgm.auto.internal:3353): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:08,055 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, a21db400-a046-4607-a494-011475c676e5, ghrun-jumawwbfgm.auto.internal:3353): on response callback success
2025-12-12 16:07:08,054 - INFO - ydb.pool.Discovery.1470822  - run: Successfully terminated discovery process
2025-12-12 16:07:08,061 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-12 16:07:08,067 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - remove_database: /Root/users/database
2025-12-12 16:07:08,068 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-12 16:07:08,074 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765555628071214&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:07:08,075 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-12 16:07:08,581 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-12 16:07:08,581 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-12 16:07:08,584 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-12 16:07:11,587 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - stop: Stopped node localhost:3353/3
2025-12-12 16:07:11,587 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: done 2
2025-12-12 16:07:11,588 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create_database
2025-12-12 16:07:12,105 - DEBUG - ya.test.1470822  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd
2025-12-12 16:07:12,106 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61109
2025-12-12 16:07:12,108 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27961
2025-12-12 16:07:12,109 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32443
2025-12-12 16:07:12,110 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19090
2025-12-12 16:07:12,111 - DEBUG - library.python.filelock.1470822  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18989
2025-12-12 16:07:12,113 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:12,113 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_l8mawnm4.log --grpc-port=61109 --mon-port=27961 --ic-port=32443
2025-12-12 16:07:12,114 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:12,116 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:6779 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_l8mawnm4.log --grpc-port=61109 --mon-port=27961 --ic-port=32443
2025-12-12 16:07:12,117 - DEBUG - ya.test.1470822  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c78/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:6779', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c78/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_l8mawnm4.log', '--grpc-port=61109', '--mon-port=27961', '--ic-port=32443')' in '/home/runner/.ya/build/build_root/dbm4/004c78/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-12 16:07:12,119 - DEBUG - ya.test.1470822  - execute: Command pid: 1614006
2025-12-12 16:07:12,119 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Started node localhost:61109/4
2025-12-12 16:07:12,121 - DEBUG - ya.test.1470822  - _get_command_output_file: Command out will be placed to ydbd.out.43
2025-12-12 16:07:12,122 - DEBUG - ya.test.1470822  - _get_command_output_file: Command err will be placed to ydbd.err.43
2025-12-12 16:07:12,123 - DEBUG - ya.test.1470822  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c78/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:07:12,124 - DEBUG - ya.test.1470822  - execute: Command pid: 1614007
2025-12-12 16:07:12,527 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) rc: 0
2025-12-12 16:07:12,528 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) elapsed time (sec): 0.4023451805114746
2025-12-12 16:07:12,528 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) idrss: 0
2025-12-12 16:07:12,528 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) inblock: 0
2025-12-12 16:07:12,528 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) isrss: 0
2025-12-12 16:07:12,528 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) ixrss: 0
2025-12-12 16:07:12,528 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) majflt: 0
2025-12-12 16:07:12,529 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) maxrss: 547600
2025-12-12 16:07:12,529 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) minflt: 17237
2025-12-12 16:07:12,529 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) msgrcv: 0
2025-12-12 16:07:12,529 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) msgsnd: 0
2025-12-12 16:07:12,529 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) nivcsw: 40
2025-12-12 16:07:12,529 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) nsignals: 0
2025-12-12 16:07:12,530 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) nswap: 0
2025-12-12 16:07:12,530 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) nvcsw: 15
2025-12-12 16:07:12,530 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) oublock: 8
2025-12-12 16:07:12,530 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) stime: 0.23655099999999998
2025-12-12 16:07:12,530 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) utime: 0.104242
2025-12-12 16:07:12,530 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) wtime: 0.404
2025-12-12 16:07:12,531 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-12 16:07:12,531 - DEBUG - ya.test.1470822  - _save_outputs: Command (pid 1614007) errors:

2025-12-12 16:07:12,531 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - start: Node 4 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:07:12,531 - DEBUG - ydb.connection.1470822  - 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-12 16:07:12,533 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:12,534 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table one
2025-12-12 16:07:12,535 - DEBUG - ydb.connection.1470822  - 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-12 16:07:12,535 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:07:12,540 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Subscribe waiter <Future at 0x7f0eac699fa0 state=pending>
2025-12-12 16:07:12,540 - DEBUG - ydb.table.SessionPool.1470822  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-12-12 16:07:12,540 - DEBUG - ydb.table.SessionPool.1470822  - _create: Created session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:12,541 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:12,541 - ERROR - ydb.table.SessionPool.1470822  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:07:12,547 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:07:12,548 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, d78e761e-2d9b-4250-a09a-3c8a1ea68c76, localhost:6779): creating call state
2025-12-12 16:07:12,548 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, d78e761e-2d9b-4250-a09a-3c8a1ea68c76, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:07:12,550 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, d78e761e-2d9b-4250-a09a-3c8a1ea68c76, localhost:6779): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-12 16:07:12,551 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:6779". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-12 16:07:12,552 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:14,337 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:14,338 - DEBUG - ydb.connection.1470822  - 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-12 16:07:14,345 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - context_resolve: Resolving endpoints for database /Root/users/database
2025-12-12 16:07:14,346 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ListEndpoints, 7e4a02a7-16fc-4f78-873a-967014bffe0c, localhost:6779): creating call state
2025-12-12 16:07:14,346 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ListEndpoints, 7e4a02a7-16fc-4f78-873a-967014bffe0c, localhost:6779): request = { database: "/Root/users/database" }
2025-12-12 16:07:14,349 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ListEndpoints, 7e4a02a7-16fc-4f78-873a-967014bffe0c, localhost:6779): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 61109 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-12 16:07:14,349 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1470822  - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jumawwbfgm.auto.internal:61109, location , ssl: False>]>
2025-12-12 16:07:14,350 - DEBUG - ydb.connection.1470822  - 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-12 16:07:14,357 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:14,357 - DEBUG - ydb.table.SessionPool.1470822  - _prepare: Preparing session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,381 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateSession, 25f19169-0ba4-4204-b353-b0a465389605, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:14,381 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateSession, 25f19169-0ba4-4204-b353-b0a465389605, ghrun-jumawwbfgm.auto.internal:61109): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:14,384 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 25f19169-0ba4-4204-b353-b0a465389605, ghrun-jumawwbfgm.auto.internal:61109): on response callback started
2025-12-12 16:07:14,385 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateSession, 25f19169-0ba4-4204-b353-b0a465389605, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" } } } }
2025-12-12 16:07:14,386 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,386 - DEBUG - ydb.table.SessionPool.1470822  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,386 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, cf66c732-e443-464a-a850-1aad17ab819b, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:14,387 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(CreateSession, 25f19169-0ba4-4204-b353-b0a465389605, ghrun-jumawwbfgm.auto.internal:61109): on response callback success
2025-12-12 16:07:14,388 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, cf66c732-e443-464a-a850-1aad17ab819b, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" 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-12 16:07:14,397 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, cf66c732-e443-464a-a850-1aad17ab819b, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-12 16:07:14,398 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,447 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, 47dbd7a3-21a9-4d91-b9dc-66b6beaebc62, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:14,448 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, 47dbd7a3-21a9-4d91-b9dc-66b6beaebc62, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" 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-12 16:07:14,453 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, 47dbd7a3-21a9-4d91-b9dc-66b6beaebc62, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-12 16:07:14,453 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,545 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, b13d2f88-7cd7-4f0b-a398-8704a0104718, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:14,546 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, b13d2f88-7cd7-4f0b-a398-8704a0104718, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" 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-12 16:07:14,551 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, b13d2f88-7cd7-4f0b-a398-8704a0104718, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-12-12 16:07:14,551 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,748 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, f13783c6-72e2-484d-96a5-8d610a2b81d0, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:14,749 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, f13783c6-72e2-484d-96a5-8d610a2b81d0, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" 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-12 16:07:14,800 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, f13783c6-72e2-484d-96a5-8d610a2b81d0, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:14,800 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,801 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: create table two
2025-12-12 16:07:14,801 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(CreateTable, 5c2f6a7c-2f69-465b-b17c-2767291f5aec, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:14,802 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(CreateTable, 5c2f6a7c-2f69-465b-b17c-2767291f5aec, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" 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-12 16:07:14,832 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(CreateTable, 5c2f6a7c-2f69-465b-b17c-2767291f5aec, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:14,833 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:14,833 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: write_some_data
2025-12-12 16:07:14,835 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, ff49d14c-4265-4a78-863e-d568c3e67c00, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:14,835 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, ff49d14c-4265-4a78-863e-d568c3e67c00, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" 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-12 16:07:15,020 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, ff49d14c-4265-4a78-863e-d568c3e67c00, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-12-12 16:07:15,021 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:15,021 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: read_some_data
2025-12-12 16:07:15,022 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(ExecuteDataQuery, 54971c0e-4498-41cf-ac63-1bc3d1103471, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:15,022 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(ExecuteDataQuery, 54971c0e-4498-41cf-ac63-1bc3d1103471, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" 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-12 16:07:15,072 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(ExecuteDataQuery, 54971c0e-4498-41cf-ac63-1bc3d1103471, ghrun-jumawwbfgm.auto.internal:61109): 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-12 16:07:15,072 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:15,073 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: drop table two
2025-12-12 16:07:15,073 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DropTable, d82dcf07-bfa7-43fe-adc0-6e7aed982a27, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:15,074 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DropTable, d82dcf07-bfa7-43fe-adc0-6e7aed982a27, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-12 16:07:15,125 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DropTable, d82dcf07-bfa7-43fe-adc0-6e7aed982a27, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:15,131 - DEBUG - ydb.table.SessionPool.1470822  - put: Put on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:15,132 - DEBUG - ydb.table.SessionPool.1470822  - stop: Requested session pool stop.
2025-12-12 16:07:15,133 - DEBUG - ydb.table.SessionPool.1470822  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:07:15,133 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroying sessions in active queue
2025-12-12 16:07:15,133 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f0eaaed4ec0>, reason: session-pool-terminated
2025-12-12 16:07:15,133 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Session <ydb.table.Session object at 0x7f0eaaed4ec0> is no longer active. Current active count 0.
2025-12-12 16:07:15,134 - DEBUG - ydb.connection.1470822  - _prepare_call: RpcState(DeleteSession, 65be8102-0690-438c-97a6-f678c19d740a, ghrun-jumawwbfgm.auto.internal:61109): creating call state
2025-12-12 16:07:15,134 - DEBUG - ydb.connection.1470822  - _log_request: RpcState(DeleteSession, 65be8102-0690-438c-97a6-f678c19d740a, ghrun-jumawwbfgm.auto.internal:61109): request = { session_id: "ydb://session/3?node_id=50003&id=ZmNkYjA0MTUtZTZiNmUzOTctNjA2MjJjNGYtMmUxYmM1MTk=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:15,135 - DEBUG - ydb.table.SessionPool.1470822  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f0eaaed4ec0>
2025-12-12 16:07:15,136 - DEBUG - ydb.table.SessionPool.1470822  - stop: Destroyed active sessions
2025-12-12 16:07:15,136 - DEBUG - ydb.table.SessionPool.1470822  - _terminate_event: Terminated session pool.
2025-12-12 16:07:15,137 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, 65be8102-0690-438c-97a6-f678c19d740a, ghrun-jumawwbfgm.auto.internal:61109): on response callback started
2025-12-12 16:07:15,137 - DEBUG - ydb.topic.1470822  - close: Close topic client
2025-12-12 16:07:15,138 - DEBUG - ydb.connection.1470822  - _log_response: RpcState(DeleteSession, 65be8102-0690-438c-97a6-f678c19d740a, ghrun-jumawwbfgm.auto.internal:61109): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:15,139 - DEBUG - ydb.connection.1470822  - _on_response_callback: RpcState(DeleteSession, 65be8102-0690-438c-97a6-f678c19d740a, ghrun-jumawwbfgm.auto.internal:61109): on response callback success
2025-12-12 16:07:15,138 - DEBUG - ydb.topic.1470822  - close: Topic client was closed
2025-12-12 16:07:15,139 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint localhost:6779
2025-12-12 16:07:15,140 - DEBUG - ydb.connection.1470822  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:61109
2025-12-12 16:07:15,143 - INFO - ydb.pool.Discovery.1470822  - run: Successfully terminated discovery process
2025-12-12 16:07:15,144 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: remove_database
2025-12-12 16:07:15,144 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - remove_database: /Root/users/database
2025-12-12 16:07:15,145 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-12-12 16:07:15,151 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1765555635148995&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:07:15,154 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-12-12 16:07:15,657 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_op: /Root/users/database: console operation done
2025-12-12 16:07:15,658 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-12-12 16:07:15,661 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1470822  - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-12-12 16:07:16,662 - INFO - ydb.tests.library.harness.kikimr_runner.1470822  - stop: Stopped node localhost:61109/4
2025-12-12 16:07:16,662 - DEBUG - __tests__.test_dynamic_tenants.1470822  - test_create_and_drop_the_same_tenant2: done 3
2025-12-12 16:07:16,664 - INFO - ya.test.1470822  - pytest_runtest_teardown: Test teardown
