2025-11-01 20:01:27,106 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 20:01:27,111 - INFO - ya.test - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true]
2025-11-01 20:01:27,112 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 20:01:27,112 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 20:01:27,115 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-11-01 20:01:34,148 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:23580/1
2025-11-01 20:01:34,150 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17764
2025-11-01 20:01:34,150 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5306
2025-11-01 20:01:34,152 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26965
2025-11-01 20:01:34,152 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15398
2025-11-01 20:01:34,152 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9452
2025-11-01 20:01:34,154 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15646
2025-11-01 20:01:34,154 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16499
2025-11-01 20:01:34,154 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24648
2025-11-01 20:01:34,156 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20212
2025-11-01 20:01:34,156 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4821
2025-11-01 20:01:34,156 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19992
2025-11-01 20:01:34,222 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4662
2025-11-01 20:01:34,301 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11929
2025-11-01 20:01:34,301 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6429
2025-11-01 20:01:34,302 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13069
2025-11-01 20:01:34,344 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22795
2025-11-01 20:01:34,344 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16149
2025-11-01 20:01:34,345 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20348
2025-11-01 20:01:34,345 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13113
2025-11-01 20:01:34,345 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6069
2025-11-01 20:01:34,345 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23472
2025-11-01 20:01:34,345 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24675
2025-11-01 20:01:34,346 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20763
2025-11-01 20:01:34,346 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23580
2025-11-01 20:01:34,346 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20355
2025-11-01 20:01:34,442 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:34,445 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26391
2025-11-01 20:01:34,446 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-11-01 20:01:34,539 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-11-01 20:01:34,539 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/vv82/00308f/r3tmp/kikimr_cluster_nswebo0l
2025-11-01 20:01:34,597 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:34,597 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7427
2025-11-01 20:01:34,598 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63732
2025-11-01 20:01:34,599 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4985
2025-11-01 20:01:34,602 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13819
2025-11-01 20:01:34,603 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13820
2025-11-01 20:01:34,641 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:34,641 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/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/vv82/00308f/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_4l2n0c4n.log --grpc-port=7427 --mon-port=63732 --ic-port=26391
2025-11-01 20:01:34,642 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:7427/1, disk_size 68719476736
2025-11-01 20:01:34,642 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:34,642 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/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/vv82/00308f/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_4l2n0c4n.log --grpc-port=7427 --mon-port=63732 --ic-port=26391
2025-11-01 20:01:34,643 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/00308f/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/vv82/00308f/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_4l2n0c4n.log', '--grpc-port=7427', '--mon-port=63732', '--ic-port=26391')' in '/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:34,645 - DEBUG - ya.test - execute: Command pid: 3354478
2025-11-01 20:01:34,646 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:7427/1
2025-11-01 20:01:34,686 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.37
2025-11-01 20:01:34,687 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.37
2025-11-01 20:01:34,687 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 20:01:34,704 - DEBUG - ya.test - execute: Command pid: 3354486
2025-11-01 20:01:35,326 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) rc: 0
2025-11-01 20:01:35,348 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) elapsed time (sec): 0.586585521697998
2025-11-01 20:01:35,361 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) idrss: 0
2025-11-01 20:01:35,361 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) inblock: 0
2025-11-01 20:01:35,361 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) isrss: 0
2025-11-01 20:01:35,363 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) ixrss: 0
2025-11-01 20:01:35,363 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) majflt: 0
2025-11-01 20:01:35,363 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) maxrss: 538712
2025-11-01 20:01:35,372 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) minflt: 16135
2025-11-01 20:01:35,373 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) msgrcv: 0
2025-11-01 20:01:35,373 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) msgsnd: 0
2025-11-01 20:01:35,373 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) nivcsw: 142
2025-11-01 20:01:35,373 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) nsignals: 0
2025-11-01 20:01:35,381 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) nswap: 0
2025-11-01 20:01:35,381 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) nvcsw: 18
2025-11-01 20:01:35,387 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) oublock: 8
2025-11-01 20:01:35,387 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) stime: 0.343313
2025-11-01 20:01:35,388 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) utime: 0.136851
2025-11-01 20:01:35,388 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) wtime: 0.604
2025-11-01 20:01:35,389 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mjpjznvske
    Host information: 
        Linux ghrun-mjpjznvske 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-11-01 20:01:35,393 - DEBUG - ya.test - _save_outputs: Command (pid 3354486) errors:

2025-11-01 20:01:35,393 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 20:01:37,714 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:37,724 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7427', '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: 26391\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 20:01:37,729 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.38
2025-11-01 20:01:37,740 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.38
2025-11-01 20:01:37,741 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7427', '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: 26391\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 20:01:37,749 - DEBUG - ya.test - execute: Command pid: 3357455
2025-11-01 20:01:38,575 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) rc: 0
2025-11-01 20:01:38,575 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) elapsed time (sec): 0.8196890354156494
2025-11-01 20:01:38,575 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) idrss: 0
2025-11-01 20:01:38,575 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) inblock: 0
2025-11-01 20:01:38,576 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) isrss: 0
2025-11-01 20:01:38,576 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) ixrss: 0
2025-11-01 20:01:38,576 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) majflt: 0
2025-11-01 20:01:38,576 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) maxrss: 546952
2025-11-01 20:01:38,576 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) minflt: 18275
2025-11-01 20:01:38,576 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) msgrcv: 0
2025-11-01 20:01:38,576 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) msgsnd: 0
2025-11-01 20:01:38,581 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) nivcsw: 202
2025-11-01 20:01:38,588 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) nsignals: 0
2025-11-01 20:01:38,593 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) nswap: 0
2025-11-01 20:01:38,593 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) nvcsw: 109
2025-11-01 20:01:38,594 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) oublock: 8
2025-11-01 20:01:38,594 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) stime: 0.34713099999999997
2025-11-01 20:01:38,599 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) utime: 0.154268
2025-11-01 20:01:38,607 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) wtime: 0.829
2025-11-01 20:01:38,608 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 20:01:38,608 - DEBUG - ya.test - _save_outputs: Command (pid 3357455) errors:

2025-11-01 20:01:38,612 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:38,621 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7427', '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-11-01 20:01:38,623 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.39
2025-11-01 20:01:38,625 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.39
2025-11-01 20:01:38,625 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7427', '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-11-01 20:01:38,656 - DEBUG - ya.test - execute: Command pid: 3358272
2025-11-01 20:01:39,772 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) rc: 0
2025-11-01 20:01:39,773 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) elapsed time (sec): 1.1132092475891113
2025-11-01 20:01:39,773 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) idrss: 0
2025-11-01 20:01:39,773 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) inblock: 0
2025-11-01 20:01:39,774 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) isrss: 0
2025-11-01 20:01:39,810 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) ixrss: 0
2025-11-01 20:01:39,811 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) majflt: 0
2025-11-01 20:01:39,811 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) maxrss: 546948
2025-11-01 20:01:39,811 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) minflt: 18271
2025-11-01 20:01:39,811 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) msgrcv: 0
2025-11-01 20:01:39,864 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) msgsnd: 0
2025-11-01 20:01:39,865 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) nivcsw: 164
2025-11-01 20:01:39,865 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) nsignals: 0
2025-11-01 20:01:39,865 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) nswap: 0
2025-11-01 20:01:39,884 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) nvcsw: 122
2025-11-01 20:01:39,916 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) oublock: 8
2025-11-01 20:01:39,916 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) stime: 0.365823
2025-11-01 20:01:39,936 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) utime: 0.16048199999999999
2025-11-01 20:01:39,941 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) wtime: 1.117
2025-11-01 20:01:39,946 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 20:01:39,946 - DEBUG - ya.test - _save_outputs: Command (pid 3358272) errors:

2025-11-01 20:01:39,947 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 20:01:39,997 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 20:01:40,018 - INFO - ya.test - 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-11-01 20:01:40,018 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-01 20:01:40,543 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:40,548 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6515
2025-11-01 20:01:40,549 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3624
2025-11-01 20:01:40,549 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28452
2025-11-01 20:01:40,551 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6288
2025-11-01 20:01:40,560 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8403
2025-11-01 20:01:40,563 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:40,564 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_avt0c4t0.log --grpc-port=6515 --mon-port=3624 --ic-port=28452
2025-11-01 20:01:40,564 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:40,564 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_avt0c4t0.log --grpc-port=6515 --mon-port=3624 --ic-port=28452
2025-11-01 20:01:40,565 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/00308f/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:7427', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_avt0c4t0.log', '--grpc-port=6515', '--mon-port=3624', '--ic-port=28452')' in '/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:40,582 - DEBUG - ya.test - execute: Command pid: 3359250
2025-11-01 20:01:40,600 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:6515/1
2025-11-01 20:01:40,644 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.40
2025-11-01 20:01:40,653 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.40
2025-11-01 20:01:40,654 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 20:01:40,656 - DEBUG - ya.test - execute: Command pid: 3359306
2025-11-01 20:01:41,436 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) rc: 0
2025-11-01 20:01:41,437 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) elapsed time (sec): 0.7692956924438477
2025-11-01 20:01:41,437 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) idrss: 0
2025-11-01 20:01:41,437 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) inblock: 0
2025-11-01 20:01:41,460 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) isrss: 0
2025-11-01 20:01:41,460 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) ixrss: 0
2025-11-01 20:01:41,460 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) majflt: 0
2025-11-01 20:01:41,469 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) maxrss: 538576
2025-11-01 20:01:41,470 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) minflt: 16128
2025-11-01 20:01:41,470 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) msgrcv: 0
2025-11-01 20:01:41,470 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) msgsnd: 0
2025-11-01 20:01:41,470 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) nivcsw: 140
2025-11-01 20:01:41,470 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) nsignals: 0
2025-11-01 20:01:41,471 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) nswap: 0
2025-11-01 20:01:41,471 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) nvcsw: 19
2025-11-01 20:01:41,471 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) oublock: 8
2025-11-01 20:01:41,471 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) stime: 0.311969
2025-11-01 20:01:41,471 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) utime: 0.12556799999999999
2025-11-01 20:01:41,471 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) wtime: 0.781
2025-11-01 20:01:41,472 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mjpjznvske
    Host information: 
        Linux ghrun-mjpjznvske 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-11-01 20:01:41,474 - DEBUG - ya.test - _save_outputs: Command (pid 3359306) errors:

2025-11-01 20:01:41,474 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 20:01:41,475 - DEBUG - ydb.connection - 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-11-01 20:01:41,477 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:41,478 - DEBUG - ydb.connection - 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-11-01 20:01:41,478 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-01 20:01:41,517 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-01 20:01:41,657 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, bd46145e-dead-4cb6-b4dd-f18f5cde86df, localhost:7427): creating call state
2025-11-01 20:01:41,612 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 20:01:41,862 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fae0d478bf0 state=pending>
2025-11-01 20:01:41,862 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-01 20:01:41,863 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:41,863 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:41,796 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, bd46145e-dead-4cb6-b4dd-f18f5cde86df, localhost:7427): request = { database: "/Root/users/database" }
2025-11-01 20:01:41,864 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 20:01:41,883 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, bd46145e-dead-4cb6-b4dd-f18f5cde86df, localhost:7427): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 6515 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-11-01 20:01:41,891 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:6515, location , ssl: False>]>
2025-11-01 20:01:41,892 - DEBUG - ydb.connection - 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-11-01 20:01:41,984 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:41,984 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:41,995 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7bbf281f-107e-4929-8e12-203417e1672d, ghrun-mjpjznvske.auto.internal:6515): creating call state
2025-11-01 20:01:41,995 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7bbf281f-107e-4929-8e12-203417e1672d, ghrun-mjpjznvske.auto.internal:6515): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:01:42,551 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7bbf281f-107e-4929-8e12-203417e1672d, ghrun-mjpjznvske.auto.internal:6515): on response callback started
2025-11-01 20:01:42,581 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7bbf281f-107e-4929-8e12-203417e1672d, ghrun-mjpjznvske.auto.internal:6515): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZGQ4YWViYjgtZDgzYzY0YmUtYWRkODFmZTEtY2IxNjkxYjM=" } } } }
2025-11-01 20:01:42,582 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:42,583 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:42,583 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7bbf281f-107e-4929-8e12-203417e1672d, ghrun-mjpjznvske.auto.internal:6515): on response callback success
2025-11-01 20:01:42,584 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 40462897-bd6b-42dd-a2c4-f104c22b4121, ghrun-mjpjznvske.auto.internal:6515): creating call state
2025-11-01 20:01:42,586 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 40462897-bd6b-42dd-a2c4-f104c22b4121, ghrun-mjpjznvske.auto.internal:6515): request = { session_id: "ydb://session/3?node_id=50000&id=ZGQ4YWViYjgtZDgzYzY0YmUtYWRkODFmZTEtY2IxNjkxYjM=" 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-11-01 20:01:42,678 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 40462897-bd6b-42dd-a2c4-f104c22b4121, ghrun-mjpjznvske.auto.internal:6515): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:42,731 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:42,793 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-01 20:01:42,794 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, c1e7dd18-bb55-41d0-8cc6-2ab5241f81c1, ghrun-mjpjznvske.auto.internal:6515): creating call state
2025-11-01 20:01:42,795 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, c1e7dd18-bb55-41d0-8cc6-2ab5241f81c1, ghrun-mjpjznvske.auto.internal:6515): request = { session_id: "ydb://session/3?node_id=50000&id=ZGQ4YWViYjgtZDgzYzY0YmUtYWRkODFmZTEtY2IxNjkxYjM=" 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-11-01 20:01:42,890 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, c1e7dd18-bb55-41d0-8cc6-2ab5241f81c1, ghrun-mjpjznvske.auto.internal:6515): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:42,913 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:42,914 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-01 20:01:42,932 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, cd34bf32-a9f7-49a3-93f0-725daa6f5169, ghrun-mjpjznvske.auto.internal:6515): creating call state
2025-11-01 20:01:42,944 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, cd34bf32-a9f7-49a3-93f0-725daa6f5169, ghrun-mjpjznvske.auto.internal:6515): request = { session_id: "ydb://session/3?node_id=50000&id=ZGQ4YWViYjgtZDgzYzY0YmUtYWRkODFmZTEtY2IxNjkxYjM=" 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-11-01 20:01:42,982 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, cd34bf32-a9f7-49a3-93f0-725daa6f5169, ghrun-mjpjznvske.auto.internal:6515): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 20:01:42,983 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:42,983 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-01 20:01:42,984 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e27d9058-8412-4e37-80df-545bcedd65ea, ghrun-mjpjznvske.auto.internal:6515): creating call state
2025-11-01 20:01:43,059 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e27d9058-8412-4e37-80df-545bcedd65ea, ghrun-mjpjznvske.auto.internal:6515): request = { session_id: "ydb://session/3?node_id=50000&id=ZGQ4YWViYjgtZDgzYzY0YmUtYWRkODFmZTEtY2IxNjkxYjM=" 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-11-01 20:01:43,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e27d9058-8412-4e37-80df-545bcedd65ea, ghrun-mjpjznvske.auto.internal:6515): 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-11-01 20:01:43,184 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:43,190 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-01 20:01:43,190 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 261bdefb-e34e-4e8f-ad6d-fbbf0c54cf7b, ghrun-mjpjznvske.auto.internal:6515): creating call state
2025-11-01 20:01:43,225 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 261bdefb-e34e-4e8f-ad6d-fbbf0c54cf7b, ghrun-mjpjznvske.auto.internal:6515): request = { session_id: "ydb://session/3?node_id=50000&id=ZGQ4YWViYjgtZDgzYzY0YmUtYWRkODFmZTEtY2IxNjkxYjM=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:43,314 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 261bdefb-e34e-4e8f-ad6d-fbbf0c54cf7b, ghrun-mjpjznvske.auto.internal:6515): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:43,338 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:43,338 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 20:01:43,344 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 20:01:43,344 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 20:01:43,348 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fae0d478e70>, reason: session-pool-terminated
2025-11-01 20:01:43,348 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fae0d478e70> is no longer active. Current active count 0.
2025-11-01 20:01:43,349 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, fb350431-56c3-459a-bae2-956e165f3afd, ghrun-mjpjznvske.auto.internal:6515): creating call state
2025-11-01 20:01:43,349 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, fb350431-56c3-459a-bae2-956e165f3afd, ghrun-mjpjznvske.auto.internal:6515): request = { session_id: "ydb://session/3?node_id=50000&id=ZGQ4YWViYjgtZDgzYzY0YmUtYWRkODFmZTEtY2IxNjkxYjM=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:01:43,344 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 20:01:43,353 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fae0d478e70>
2025-11-01 20:01:43,356 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, fb350431-56c3-459a-bae2-956e165f3afd, ghrun-mjpjznvske.auto.internal:6515): on response callback started
2025-11-01 20:01:43,389 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, fb350431-56c3-459a-bae2-956e165f3afd, ghrun-mjpjznvske.auto.internal:6515): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:43,382 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 20:01:43,421 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, fb350431-56c3-459a-bae2-956e165f3afd, ghrun-mjpjznvske.auto.internal:6515): on response callback success
2025-11-01 20:01:43,422 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 20:01:43,429 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 20:01:43,430 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:43,431 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:6515
2025-11-01 20:01:43,433 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 20:01:43,439 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-01 20:01:43,440 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-01 20:01:43,440 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-01 20:01:43,450 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1762027303443601&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 20:01:43,451 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-01 20:01:43,958 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-01 20:01:43,960 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-01 20:01:43,964 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-01 20:01:44,973 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:6515/1
2025-11-01 20:01:44,975 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 0
2025-11-01 20:01:44,992 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-01 20:01:45,507 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:45,508 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63771
2025-11-01 20:01:45,513 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24212
2025-11-01 20:01:45,513 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4098
2025-11-01 20:01:45,514 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1870
2025-11-01 20:01:45,514 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20084
2025-11-01 20:01:45,515 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:45,515 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_vztbvfth.log --grpc-port=63771 --mon-port=24212 --ic-port=4098
2025-11-01 20:01:45,515 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:45,540 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_vztbvfth.log --grpc-port=63771 --mon-port=24212 --ic-port=4098
2025-11-01 20:01:45,541 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/00308f/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:7427', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_vztbvfth.log', '--grpc-port=63771', '--mon-port=24212', '--ic-port=4098')' in '/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:45,578 - DEBUG - ya.test - execute: Command pid: 3361710
2025-11-01 20:01:45,579 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:63771/2
2025-11-01 20:01:45,596 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.41
2025-11-01 20:01:45,597 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.41
2025-11-01 20:01:45,597 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 20:01:45,604 - DEBUG - ya.test - execute: Command pid: 3361715
2025-11-01 20:01:46,094 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) rc: 0
2025-11-01 20:01:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) elapsed time (sec): 0.48984456062316895
2025-11-01 20:01:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) idrss: 0
2025-11-01 20:01:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) inblock: 0
2025-11-01 20:01:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) isrss: 0
2025-11-01 20:01:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) ixrss: 0
2025-11-01 20:01:46,095 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) majflt: 0
2025-11-01 20:01:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) maxrss: 538576
2025-11-01 20:01:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) minflt: 16130
2025-11-01 20:01:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) msgrcv: 0
2025-11-01 20:01:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) msgsnd: 0
2025-11-01 20:01:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) nivcsw: 123
2025-11-01 20:01:46,096 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) nsignals: 0
2025-11-01 20:01:46,120 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) nswap: 0
2025-11-01 20:01:46,120 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) nvcsw: 22
2025-11-01 20:01:46,121 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) oublock: 8
2025-11-01 20:01:46,121 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) stime: 0.277894
2025-11-01 20:01:46,125 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) utime: 0.11359
2025-11-01 20:01:46,126 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) wtime: 0.496
2025-11-01 20:01:46,126 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mjpjznvske
    Host information: 
        Linux ghrun-mjpjznvske 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-11-01 20:01:46,126 - DEBUG - ya.test - _save_outputs: Command (pid 3361715) errors:

2025-11-01 20:01:46,126 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 20:01:46,127 - DEBUG - ydb.connection - 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-11-01 20:01:46,128 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:46,129 - DEBUG - ydb.connection - 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-11-01 20:01:46,133 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-01 20:01:46,135 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-01 20:01:46,144 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 20:01:46,153 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 15f7c876-aa82-42fc-99ed-1237ab93638d, localhost:7427): creating call state
2025-11-01 20:01:46,154 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fae0d4e7260 state=pending>
2025-11-01 20:01:46,162 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 15f7c876-aa82-42fc-99ed-1237ab93638d, localhost:7427): request = { database: "/Root/users/database" }
2025-11-01 20:01:46,164 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-01 20:01:46,167 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:46,168 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:46,172 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 15f7c876-aa82-42fc-99ed-1237ab93638d, localhost:7427): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-11-01 20:01:46,180 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 20:01:46,186 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:7427". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-11-01 20:01:46,188 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:47,972 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:47,980 - DEBUG - ydb.connection - 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-11-01 20:01:47,986 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-01 20:01:48,052 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 93dfa02e-353b-40d3-bdc5-0521b960e064, localhost:7427): creating call state
2025-11-01 20:01:48,074 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 93dfa02e-353b-40d3-bdc5-0521b960e064, localhost:7427): request = { database: "/Root/users/database" }
2025-11-01 20:01:48,168 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 93dfa02e-353b-40d3-bdc5-0521b960e064, localhost:7427): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 63771 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-11-01 20:01:48,223 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:63771, location , ssl: False>]>
2025-11-01 20:01:48,246 - DEBUG - ydb.connection - 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-11-01 20:01:48,280 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:48,281 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,283 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ed9c7262-694d-4efe-9826-c894c98b2c6b, ghrun-mjpjznvske.auto.internal:63771): creating call state
2025-11-01 20:01:48,286 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ed9c7262-694d-4efe-9826-c894c98b2c6b, ghrun-mjpjznvske.auto.internal:63771): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:01:48,308 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ed9c7262-694d-4efe-9826-c894c98b2c6b, ghrun-mjpjznvske.auto.internal:63771): on response callback started
2025-11-01 20:01:48,320 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ed9c7262-694d-4efe-9826-c894c98b2c6b, ghrun-mjpjznvske.auto.internal:63771): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=YzQ0ZjgxNTEtZDM0YmIwMzMtM2Y2ZGVmYjUtNjM2ZTE5ZjE=" } } } }
2025-11-01 20:01:48,356 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,356 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,357 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ed9c7262-694d-4efe-9826-c894c98b2c6b, ghrun-mjpjznvske.auto.internal:63771): on response callback success
2025-11-01 20:01:48,359 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 17d53b01-4016-424b-b9be-349c55adc97a, ghrun-mjpjznvske.auto.internal:63771): creating call state
2025-11-01 20:01:48,360 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 17d53b01-4016-424b-b9be-349c55adc97a, ghrun-mjpjznvske.auto.internal:63771): request = { session_id: "ydb://session/3?node_id=50001&id=YzQ0ZjgxNTEtZDM0YmIwMzMtM2Y2ZGVmYjUtNjM2ZTE5ZjE=" 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-11-01 20:01:48,424 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 17d53b01-4016-424b-b9be-349c55adc97a, ghrun-mjpjznvske.auto.internal:63771): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:48,425 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,425 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-01 20:01:48,426 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 550d3bb3-8b06-4c1b-80e3-89caef5696ed, ghrun-mjpjznvske.auto.internal:63771): creating call state
2025-11-01 20:01:48,431 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 550d3bb3-8b06-4c1b-80e3-89caef5696ed, ghrun-mjpjznvske.auto.internal:63771): request = { session_id: "ydb://session/3?node_id=50001&id=YzQ0ZjgxNTEtZDM0YmIwMzMtM2Y2ZGVmYjUtNjM2ZTE5ZjE=" 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-11-01 20:01:48,488 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 550d3bb3-8b06-4c1b-80e3-89caef5696ed, ghrun-mjpjznvske.auto.internal:63771): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:48,489 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,516 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-01 20:01:48,517 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d9b0a899-64d4-436d-a79c-bbabbcd02ac3, ghrun-mjpjznvske.auto.internal:63771): creating call state
2025-11-01 20:01:48,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d9b0a899-64d4-436d-a79c-bbabbcd02ac3, ghrun-mjpjznvske.auto.internal:63771): request = { session_id: "ydb://session/3?node_id=50001&id=YzQ0ZjgxNTEtZDM0YmIwMzMtM2Y2ZGVmYjUtNjM2ZTE5ZjE=" 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-11-01 20:01:48,570 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d9b0a899-64d4-436d-a79c-bbabbcd02ac3, ghrun-mjpjznvske.auto.internal:63771): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 20:01:48,581 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,581 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-01 20:01:48,582 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d4410af7-58ae-4f6a-ae87-e17b7fdff308, ghrun-mjpjznvske.auto.internal:63771): creating call state
2025-11-01 20:01:48,582 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d4410af7-58ae-4f6a-ae87-e17b7fdff308, ghrun-mjpjznvske.auto.internal:63771): request = { session_id: "ydb://session/3?node_id=50001&id=YzQ0ZjgxNTEtZDM0YmIwMzMtM2Y2ZGVmYjUtNjM2ZTE5ZjE=" 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-11-01 20:01:48,627 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d4410af7-58ae-4f6a-ae87-e17b7fdff308, ghrun-mjpjznvske.auto.internal:63771): 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-11-01 20:01:48,640 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,641 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-01 20:01:48,641 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 6d5a188d-f694-4681-ab89-450db78c6d84, ghrun-mjpjznvske.auto.internal:63771): creating call state
2025-11-01 20:01:48,642 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 6d5a188d-f694-4681-ab89-450db78c6d84, ghrun-mjpjznvske.auto.internal:63771): request = { session_id: "ydb://session/3?node_id=50001&id=YzQ0ZjgxNTEtZDM0YmIwMzMtM2Y2ZGVmYjUtNjM2ZTE5ZjE=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:48,677 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 6d5a188d-f694-4681-ab89-450db78c6d84, ghrun-mjpjznvske.auto.internal:63771): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:48,679 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,679 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 20:01:48,679 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 20:01:48,679 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 20:01:48,684 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 20:01:48,689 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fae0d4e7fd0>, reason: session-pool-terminated
2025-11-01 20:01:48,689 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fae0d4e7fd0> is no longer active. Current active count 0.
2025-11-01 20:01:48,693 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 39cc0e31-f914-4acd-b7d7-5bb16402becb, ghrun-mjpjznvske.auto.internal:63771): creating call state
2025-11-01 20:01:48,705 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 39cc0e31-f914-4acd-b7d7-5bb16402becb, ghrun-mjpjznvske.auto.internal:63771): request = { session_id: "ydb://session/3?node_id=50001&id=YzQ0ZjgxNTEtZDM0YmIwMzMtM2Y2ZGVmYjUtNjM2ZTE5ZjE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:01:48,728 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fae0d4e7fd0>
2025-11-01 20:01:48,729 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 39cc0e31-f914-4acd-b7d7-5bb16402becb, ghrun-mjpjznvske.auto.internal:63771): on response callback started
2025-11-01 20:01:48,750 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 39cc0e31-f914-4acd-b7d7-5bb16402becb, ghrun-mjpjznvske.auto.internal:63771): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:48,750 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 39cc0e31-f914-4acd-b7d7-5bb16402becb, ghrun-mjpjznvske.auto.internal:63771): on response callback success
2025-11-01 20:01:48,749 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 20:01:48,751 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 20:01:48,762 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 20:01:48,763 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:48,763 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:63771
2025-11-01 20:01:48,764 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 20:01:48,765 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-01 20:01:48,769 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-01 20:01:48,769 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-01 20:01:48,784 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1762027308773552&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 20:01:48,784 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-01 20:01:49,293 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-01 20:01:49,293 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-01 20:01:49,298 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-01 20:01:50,299 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:63771/2
2025-11-01 20:01:50,299 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 1
2025-11-01 20:01:50,300 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-01 20:01:50,830 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:50,831 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19847
2025-11-01 20:01:50,846 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18748
2025-11-01 20:01:50,852 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29151
2025-11-01 20:01:50,852 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20156
2025-11-01 20:01:50,852 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6598
2025-11-01 20:01:50,854 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:50,854 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_lssub96i.log --grpc-port=19847 --mon-port=18748 --ic-port=29151
2025-11-01 20:01:50,855 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:50,855 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_lssub96i.log --grpc-port=19847 --mon-port=18748 --ic-port=29151
2025-11-01 20:01:50,856 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/00308f/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:7427', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_lssub96i.log', '--grpc-port=19847', '--mon-port=18748', '--ic-port=29151')' in '/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:50,858 - DEBUG - ya.test - execute: Command pid: 3365892
2025-11-01 20:01:50,859 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:19847/3
2025-11-01 20:01:50,876 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.42
2025-11-01 20:01:50,884 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.42
2025-11-01 20:01:50,885 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 20:01:50,889 - DEBUG - ya.test - execute: Command pid: 3365905
2025-11-01 20:01:51,895 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) rc: 0
2025-11-01 20:01:51,896 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) elapsed time (sec): 1.0057544708251953
2025-11-01 20:01:51,897 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) idrss: 0
2025-11-01 20:01:51,897 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) inblock: 0
2025-11-01 20:01:51,897 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) isrss: 0
2025-11-01 20:01:51,897 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) ixrss: 0
2025-11-01 20:01:51,897 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) majflt: 0
2025-11-01 20:01:51,898 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) maxrss: 538704
2025-11-01 20:01:51,898 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) minflt: 16133
2025-11-01 20:01:51,898 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) msgrcv: 0
2025-11-01 20:01:51,898 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) msgsnd: 0
2025-11-01 20:01:51,898 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) nivcsw: 141
2025-11-01 20:01:51,898 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) nsignals: 0
2025-11-01 20:01:51,898 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) nswap: 0
2025-11-01 20:01:51,899 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) nvcsw: 22
2025-11-01 20:01:51,899 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) oublock: 8
2025-11-01 20:01:51,908 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) stime: 0.29181199999999996
2025-11-01 20:01:51,908 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) utime: 0.154144
2025-11-01 20:01:51,925 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) wtime: 1.007
2025-11-01 20:01:51,925 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mjpjznvske
    Host information: 
        Linux ghrun-mjpjznvske 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-11-01 20:01:51,925 - DEBUG - ya.test - _save_outputs: Command (pid 3365905) errors:

2025-11-01 20:01:51,925 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 3 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 20:01:51,926 - DEBUG - ydb.connection - 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-11-01 20:01:51,928 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:51,938 - DEBUG - ydb.connection - 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-11-01 20:01:51,934 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-01 20:01:51,952 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 20:01:51,953 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fae0d4df3a0 state=pending>
2025-11-01 20:01:51,953 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-01 20:01:51,953 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:51,953 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:51,954 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 20:01:51,957 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-01 20:01:51,976 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6b558202-a1ee-4c93-b791-a6df1d7e2081, localhost:7427): creating call state
2025-11-01 20:01:51,977 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6b558202-a1ee-4c93-b791-a6df1d7e2081, localhost:7427): request = { database: "/Root/users/database" }
2025-11-01 20:01:51,981 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6b558202-a1ee-4c93-b791-a6df1d7e2081, localhost:7427): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { self_location: "1" } } } }
2025-11-01 20:01:51,982 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints []>
2025-11-01 20:01:51,995 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:53,203 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:53,204 - DEBUG - ydb.connection - 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-11-01 20:01:53,220 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-01 20:01:53,256 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 8a64355c-a47b-4aaf-809c-807fc68b0a93, localhost:7427): creating call state
2025-11-01 20:01:53,257 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 8a64355c-a47b-4aaf-809c-807fc68b0a93, localhost:7427): request = { database: "/Root/users/database" }
2025-11-01 20:01:53,265 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 8a64355c-a47b-4aaf-809c-807fc68b0a93, localhost:7427): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 19847 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-11-01 20:01:53,272 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:19847, location , ssl: False>]>
2025-11-01 20:01:53,299 - DEBUG - ydb.connection - 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-11-01 20:01:53,322 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:53,322 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,323 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, aef44909-3b2e-4154-8bf6-114ebf7893ba, ghrun-mjpjznvske.auto.internal:19847): creating call state
2025-11-01 20:01:53,329 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, aef44909-3b2e-4154-8bf6-114ebf7893ba, ghrun-mjpjznvske.auto.internal:19847): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:01:53,345 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, aef44909-3b2e-4154-8bf6-114ebf7893ba, ghrun-mjpjznvske.auto.internal:19847): on response callback started
2025-11-01 20:01:53,352 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, aef44909-3b2e-4154-8bf6-114ebf7893ba, ghrun-mjpjznvske.auto.internal:19847): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=YjY0NzUyNDgtMmJkYjdlZDMtNTFkMzM4MzktYTcwN2QwZQ==" } } } }
2025-11-01 20:01:53,353 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,355 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,356 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, d0554352-0767-4f55-b152-cd75c5e9825b, ghrun-mjpjznvske.auto.internal:19847): creating call state
2025-11-01 20:01:53,371 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, aef44909-3b2e-4154-8bf6-114ebf7893ba, ghrun-mjpjznvske.auto.internal:19847): on response callback success
2025-11-01 20:01:53,372 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, d0554352-0767-4f55-b152-cd75c5e9825b, ghrun-mjpjznvske.auto.internal:19847): request = { session_id: "ydb://session/3?node_id=50002&id=YjY0NzUyNDgtMmJkYjdlZDMtNTFkMzM4MzktYTcwN2QwZQ==" 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-11-01 20:01:53,464 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, d0554352-0767-4f55-b152-cd75c5e9825b, ghrun-mjpjznvske.auto.internal:19847): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:53,465 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,465 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-01 20:01:53,466 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 72cb5443-755b-483d-b2c0-a2e044f095c6, ghrun-mjpjznvske.auto.internal:19847): creating call state
2025-11-01 20:01:53,466 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 72cb5443-755b-483d-b2c0-a2e044f095c6, ghrun-mjpjznvske.auto.internal:19847): request = { session_id: "ydb://session/3?node_id=50002&id=YjY0NzUyNDgtMmJkYjdlZDMtNTFkMzM4MzktYTcwN2QwZQ==" 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-11-01 20:01:53,581 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 72cb5443-755b-483d-b2c0-a2e044f095c6, ghrun-mjpjznvske.auto.internal:19847): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:53,582 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,582 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-01 20:01:53,583 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3792a018-7f75-4e9d-bb56-ced47f7cb047, ghrun-mjpjznvske.auto.internal:19847): creating call state
2025-11-01 20:01:53,583 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3792a018-7f75-4e9d-bb56-ced47f7cb047, ghrun-mjpjznvske.auto.internal:19847): request = { session_id: "ydb://session/3?node_id=50002&id=YjY0NzUyNDgtMmJkYjdlZDMtNTFkMzM4MzktYTcwN2QwZQ==" 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-11-01 20:01:53,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3792a018-7f75-4e9d-bb56-ced47f7cb047, ghrun-mjpjznvske.auto.internal:19847): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 20:01:53,615 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,615 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-01 20:01:53,618 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9f3902a9-957b-405a-b27c-d342f5ce30b7, ghrun-mjpjznvske.auto.internal:19847): creating call state
2025-11-01 20:01:53,618 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9f3902a9-957b-405a-b27c-d342f5ce30b7, ghrun-mjpjznvske.auto.internal:19847): request = { session_id: "ydb://session/3?node_id=50002&id=YjY0NzUyNDgtMmJkYjdlZDMtNTFkMzM4MzktYTcwN2QwZQ==" 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-11-01 20:01:53,662 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9f3902a9-957b-405a-b27c-d342f5ce30b7, ghrun-mjpjznvske.auto.internal:19847): 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-11-01 20:01:53,663 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,663 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-01 20:01:53,664 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, f14609a1-0296-4985-bac9-c54e60160c47, ghrun-mjpjznvske.auto.internal:19847): creating call state
2025-11-01 20:01:53,680 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, f14609a1-0296-4985-bac9-c54e60160c47, ghrun-mjpjznvske.auto.internal:19847): request = { session_id: "ydb://session/3?node_id=50002&id=YjY0NzUyNDgtMmJkYjdlZDMtNTFkMzM4MzktYTcwN2QwZQ==" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:01:53,736 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, f14609a1-0296-4985-bac9-c54e60160c47, ghrun-mjpjznvske.auto.internal:19847): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:53,736 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,736 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 20:01:53,737 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 20:01:53,737 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 20:01:53,737 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fae0d4de310>, reason: session-pool-terminated
2025-11-01 20:01:53,737 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fae0d4de310> is no longer active. Current active count 0.
2025-11-01 20:01:53,737 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d00046d6-9eab-4b35-8bde-39307bf92903, ghrun-mjpjznvske.auto.internal:19847): creating call state
2025-11-01 20:01:53,738 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d00046d6-9eab-4b35-8bde-39307bf92903, ghrun-mjpjznvske.auto.internal:19847): request = { session_id: "ydb://session/3?node_id=50002&id=YjY0NzUyNDgtMmJkYjdlZDMtNTFkMzM4MzktYTcwN2QwZQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:01:53,746 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fae0d4de310>
2025-11-01 20:01:53,746 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 20:01:53,738 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 20:01:53,749 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d00046d6-9eab-4b35-8bde-39307bf92903, ghrun-mjpjznvske.auto.internal:19847): on response callback started
2025-11-01 20:01:53,765 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d00046d6-9eab-4b35-8bde-39307bf92903, ghrun-mjpjznvske.auto.internal:19847): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:01:53,766 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d00046d6-9eab-4b35-8bde-39307bf92903, ghrun-mjpjznvske.auto.internal:19847): on response callback success
2025-11-01 20:01:53,754 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 20:01:53,768 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 20:01:53,768 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:53,768 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:19847
2025-11-01 20:01:53,772 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 20:01:53,773 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-01 20:01:53,773 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-01 20:01:53,774 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-01 20:01:53,784 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1762027313779111&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 20:01:53,784 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-01 20:01:54,290 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-01 20:01:54,290 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-01 20:01:54,296 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-01 20:01:57,300 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:19847/3
2025-11-01 20:01:57,300 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 2
2025-11-01 20:01:57,301 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-01 20:01:57,826 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd
2025-11-01 20:01:57,844 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27337
2025-11-01 20:01:57,848 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20782
2025-11-01 20:01:57,888 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27068
2025-11-01 20:01:57,888 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17841
2025-11-01 20:01:57,903 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9739
2025-11-01 20:01:57,904 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:57,905 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_k_j_loay.log --grpc-port=27337 --mon-port=20782 --ic-port=27068
2025-11-01 20:01:57,905 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:57,905 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:7427 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_k_j_loay.log --grpc-port=27337 --mon-port=20782 --ic-port=27068
2025-11-01 20:01:57,906 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/00308f/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:7427', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/vv82/00308f/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_k_j_loay.log', '--grpc-port=27337', '--mon-port=20782', '--ic-port=27068')' in '/home/runner/.ya/build/build_root/vv82/00308f/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-11-01 20:01:57,924 - DEBUG - ya.test - execute: Command pid: 3370957
2025-11-01 20:01:57,968 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:27337/4
2025-11-01 20:01:58,093 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.43
2025-11-01 20:01:58,097 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.43
2025-11-01 20:01:58,106 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00308f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 20:01:58,129 - DEBUG - ya.test - execute: Command pid: 3371041
2025-11-01 20:01:58,904 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) rc: 0
2025-11-01 20:01:58,912 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) elapsed time (sec): 0.7693665027618408
2025-11-01 20:01:58,913 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) idrss: 0
2025-11-01 20:01:58,913 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) inblock: 0
2025-11-01 20:01:58,913 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) isrss: 0
2025-11-01 20:01:58,913 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) ixrss: 0
2025-11-01 20:01:58,913 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) majflt: 0
2025-11-01 20:01:58,913 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) maxrss: 538576
2025-11-01 20:01:58,914 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) minflt: 16128
2025-11-01 20:01:58,914 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) msgrcv: 0
2025-11-01 20:01:58,914 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) msgsnd: 0
2025-11-01 20:01:58,915 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) nivcsw: 149
2025-11-01 20:01:58,916 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) nsignals: 0
2025-11-01 20:01:58,917 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) nswap: 0
2025-11-01 20:01:58,917 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) nvcsw: 18
2025-11-01 20:01:58,918 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) oublock: 8
2025-11-01 20:01:58,924 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) stime: 0.34739
2025-11-01 20:01:58,924 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) utime: 0.13039499999999998
2025-11-01 20:01:58,924 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) wtime: 0.773
2025-11-01 20:01:58,924 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-mjpjznvske
    Host information: 
        Linux ghrun-mjpjznvske 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-11-01 20:01:58,925 - DEBUG - ya.test - _save_outputs: Command (pid 3371041) errors:

2025-11-01 20:01:58,925 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 4 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 20:01:58,925 - DEBUG - ydb.connection - 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-11-01 20:01:58,927 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:01:58,932 - DEBUG - ydb.connection - 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-11-01 20:01:58,929 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-01 20:01:58,938 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 20:01:58,941 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-01 20:01:58,947 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fae0d4d9370 state=pending>
2025-11-01 20:01:58,954 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-01 20:01:58,953 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 0380a7a2-3e3f-4acf-bacf-59de5ed703f0, localhost:7427): creating call state
2025-11-01 20:01:58,973 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 0380a7a2-3e3f-4acf-bacf-59de5ed703f0, localhost:7427): request = { database: "/Root/users/database" }
2025-11-01 20:01:58,979 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 0380a7a2-3e3f-4acf-bacf-59de5ed703f0, localhost:7427): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { self_location: "1" } } } }
2025-11-01 20:01:59,005 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints []>
2025-11-01 20:01:59,006 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:01:58,973 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:01:59,009 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:01:59,009 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 20:02:00,280 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:02:00,280 - DEBUG - ydb.connection - 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-11-01 20:02:00,294 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-01 20:02:00,294 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 4f546372-12e8-4015-8354-fd7375ae8140, localhost:7427): creating call state
2025-11-01 20:02:00,295 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 4f546372-12e8-4015-8354-fd7375ae8140, localhost:7427): request = { database: "/Root/users/database" }
2025-11-01 20:02:00,299 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 4f546372-12e8-4015-8354-fd7375ae8140, localhost:7427): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 27337 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-11-01 20:02:00,300 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:27337, location , ssl: False>]>
2025-11-01 20:02:00,300 - DEBUG - ydb.connection - 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-11-01 20:02:00,309 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:02:00,312 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,312 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 22d646fd-1a11-4fb0-afe1-8c3e1e5afd6d, ghrun-mjpjznvske.auto.internal:27337): creating call state
2025-11-01 20:02:00,313 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 22d646fd-1a11-4fb0-afe1-8c3e1e5afd6d, ghrun-mjpjznvske.auto.internal:27337): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:02:00,322 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 22d646fd-1a11-4fb0-afe1-8c3e1e5afd6d, ghrun-mjpjznvske.auto.internal:27337): on response callback started
2025-11-01 20:02:00,323 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 22d646fd-1a11-4fb0-afe1-8c3e1e5afd6d, ghrun-mjpjznvske.auto.internal:27337): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=MmIwZTJlNDItODU5NzVkZDctZWQzZTM1ZmEtN2M2MTI2ZDc=" } } } }
2025-11-01 20:02:00,324 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,324 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,324 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 22d646fd-1a11-4fb0-afe1-8c3e1e5afd6d, ghrun-mjpjznvske.auto.internal:27337): on response callback success
2025-11-01 20:02:00,325 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 1d55a620-fbd9-4cae-8963-518aacf65dd4, ghrun-mjpjznvske.auto.internal:27337): creating call state
2025-11-01 20:02:00,326 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 1d55a620-fbd9-4cae-8963-518aacf65dd4, ghrun-mjpjznvske.auto.internal:27337): request = { session_id: "ydb://session/3?node_id=50003&id=MmIwZTJlNDItODU5NzVkZDctZWQzZTM1ZmEtN2M2MTI2ZDc=" 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-11-01 20:02:00,413 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 1d55a620-fbd9-4cae-8963-518aacf65dd4, ghrun-mjpjznvske.auto.internal:27337): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:02:00,413 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,414 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-01 20:02:00,415 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 41e2abad-8873-4cf6-bfcf-81e6f147d612, ghrun-mjpjznvske.auto.internal:27337): creating call state
2025-11-01 20:02:00,416 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 41e2abad-8873-4cf6-bfcf-81e6f147d612, ghrun-mjpjznvske.auto.internal:27337): request = { session_id: "ydb://session/3?node_id=50003&id=MmIwZTJlNDItODU5NzVkZDctZWQzZTM1ZmEtN2M2MTI2ZDc=" 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-11-01 20:02:00,482 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 41e2abad-8873-4cf6-bfcf-81e6f147d612, ghrun-mjpjznvske.auto.internal:27337): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:02:00,506 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,506 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-01 20:02:00,507 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 194ed748-b4d0-46c8-b92b-640ad5d9c5c8, ghrun-mjpjznvske.auto.internal:27337): creating call state
2025-11-01 20:02:00,516 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 194ed748-b4d0-46c8-b92b-640ad5d9c5c8, ghrun-mjpjznvske.auto.internal:27337): request = { session_id: "ydb://session/3?node_id=50003&id=MmIwZTJlNDItODU5NzVkZDctZWQzZTM1ZmEtN2M2MTI2ZDc=" 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-11-01 20:02:00,557 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 194ed748-b4d0-46c8-b92b-640ad5d9c5c8, ghrun-mjpjznvske.auto.internal:27337): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 20:02:00,570 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,580 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-01 20:02:00,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 013371e2-e1fc-46d8-89a0-a08c040aa103, ghrun-mjpjznvske.auto.internal:27337): creating call state
2025-11-01 20:02:00,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 013371e2-e1fc-46d8-89a0-a08c040aa103, ghrun-mjpjznvske.auto.internal:27337): request = { session_id: "ydb://session/3?node_id=50003&id=MmIwZTJlNDItODU5NzVkZDctZWQzZTM1ZmEtN2M2MTI2ZDc=" 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-11-01 20:02:00,631 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 013371e2-e1fc-46d8-89a0-a08c040aa103, ghrun-mjpjznvske.auto.internal:27337): 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-11-01 20:02:00,650 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,651 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-01 20:02:00,652 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, d858134d-9ac8-446c-b932-3ea301d46aec, ghrun-mjpjznvske.auto.internal:27337): creating call state
2025-11-01 20:02:00,662 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, d858134d-9ac8-446c-b932-3ea301d46aec, ghrun-mjpjznvske.auto.internal:27337): request = { session_id: "ydb://session/3?node_id=50003&id=MmIwZTJlNDItODU5NzVkZDctZWQzZTM1ZmEtN2M2MTI2ZDc=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 20:02:00,728 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, d858134d-9ac8-446c-b932-3ea301d46aec, ghrun-mjpjznvske.auto.internal:27337): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:02:00,729 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,729 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 20:02:00,730 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 20:02:00,730 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 20:02:00,730 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 20:02:00,731 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fae0d5036c0>, reason: session-pool-terminated
2025-11-01 20:02:00,732 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fae0d5036c0> is no longer active. Current active count 0.
2025-11-01 20:02:00,732 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 06d7627d-c731-47e4-957f-190b2fbf2194, ghrun-mjpjznvske.auto.internal:27337): creating call state
2025-11-01 20:02:00,733 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 06d7627d-c731-47e4-957f-190b2fbf2194, ghrun-mjpjznvske.auto.internal:27337): request = { session_id: "ydb://session/3?node_id=50003&id=MmIwZTJlNDItODU5NzVkZDctZWQzZTM1ZmEtN2M2MTI2ZDc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 20:02:00,734 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fae0d5036c0>
2025-11-01 20:02:00,734 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 06d7627d-c731-47e4-957f-190b2fbf2194, ghrun-mjpjznvske.auto.internal:27337): on response callback started
2025-11-01 20:02:00,744 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 06d7627d-c731-47e4-957f-190b2fbf2194, ghrun-mjpjznvske.auto.internal:27337): response = { operation { ready: true status: SUCCESS } }
2025-11-01 20:02:00,745 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 06d7627d-c731-47e4-957f-190b2fbf2194, ghrun-mjpjznvske.auto.internal:27337): on response callback success
2025-11-01 20:02:00,735 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 20:02:00,746 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 20:02:00,749 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 20:02:00,749 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7427
2025-11-01 20:02:00,750 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:27337
2025-11-01 20:02:00,751 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 20:02:00,751 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-01 20:02:00,751 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-01 20:02:00,752 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-01 20:02:00,764 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database&cmstid=72057594037936131&txid=1762027320757669&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 20:02:00,765 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-01 20:02:01,276 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-01 20:02:01,276 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-01 20:02:01,280 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-01 20:02:04,282 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:27337/4
2025-11-01 20:02:04,282 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 3
2025-11-01 20:02:04,284 - INFO - ya.test - pytest_runtest_teardown: Test teardown
