2025-11-06 10:01:20,413 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:01:20,414 - INFO - ya.test - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true]
2025-11-06 10:01:20,414 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:01:20,414 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-06 10:01:20,414 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-11-06 10:01:23,416 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1763/1
2025-11-06 10:01:23,420 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3765
2025-11-06 10:01:23,420 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14562
2025-11-06 10:01:23,421 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9033
2025-11-06 10:01:23,421 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11115
2025-11-06 10:01:23,421 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13164
2025-11-06 10:01:23,421 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5739
2025-11-06 10:01:23,421 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6515
2025-11-06 10:01:23,421 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16890
2025-11-06 10:01:23,421 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18869
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14785
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32138
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14362
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7170
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3713
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32173
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20185
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8954
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12785
2025-11-06 10:01:23,422 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3503
2025-11-06 10:01:23,423 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22743
2025-11-06 10:01:23,423 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31809
2025-11-06 10:01:23,423 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22787
2025-11-06 10:01:23,423 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5621
2025-11-06 10:01:23,423 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1763
2025-11-06 10:01:23,423 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1320
2025-11-06 10:01:23,481 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:23,482 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7996
2025-11-06 10:01:23,483 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-11-06 10:01:23,483 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-11-06 10:01:23,483 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/4esq/004cd1/r3tmp/kikimr_cluster_oc5ecc5q
2025-11-06 10:01:23,521 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:23,522 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1347
2025-11-06 10:01:23,522 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1434
2025-11-06 10:01:23,523 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62018
2025-11-06 10:01:23,523 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3048
2025-11-06 10:01:23,524 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:23,524 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/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/4esq/004cd1/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_xa2wq12k.log --grpc-port=1347 --mon-port=1434 --ic-port=7996
2025-11-06 10:01:23,524 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:1347/1, disk_size 68719476736
2025-11-06 10:01:23,524 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:23,525 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/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/4esq/004cd1/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_xa2wq12k.log --grpc-port=1347 --mon-port=1434 --ic-port=7996
2025-11-06 10:01:23,526 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4esq/004cd1/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/4esq/004cd1/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_xa2wq12k.log', '--grpc-port=1347', '--mon-port=1434', '--ic-port=7996')' in '/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:23,527 - DEBUG - ya.test - execute: Command pid: 820130
2025-11-06 10:01:23,527 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:1347/1
2025-11-06 10:01:23,529 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.37
2025-11-06 10:01:23,530 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.37
2025-11-06 10:01:23,531 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:01:23,532 - DEBUG - ya.test - execute: Command pid: 820151
2025-11-06 10:01:23,859 - DEBUG - ya.test - _save_outputs: Command (pid 820151) rc: 0
2025-11-06 10:01:23,859 - DEBUG - ya.test - _save_outputs: Command (pid 820151) elapsed time (sec): 0.32613253593444824
2025-11-06 10:01:23,859 - DEBUG - ya.test - _save_outputs: Command (pid 820151) idrss: 0
2025-11-06 10:01:23,859 - DEBUG - ya.test - _save_outputs: Command (pid 820151) inblock: 0
2025-11-06 10:01:23,860 - DEBUG - ya.test - _save_outputs: Command (pid 820151) isrss: 0
2025-11-06 10:01:23,860 - DEBUG - ya.test - _save_outputs: Command (pid 820151) ixrss: 0
2025-11-06 10:01:23,860 - DEBUG - ya.test - _save_outputs: Command (pid 820151) majflt: 0
2025-11-06 10:01:23,860 - DEBUG - ya.test - _save_outputs: Command (pid 820151) maxrss: 538764
2025-11-06 10:01:23,860 - DEBUG - ya.test - _save_outputs: Command (pid 820151) minflt: 16251
2025-11-06 10:01:23,861 - DEBUG - ya.test - _save_outputs: Command (pid 820151) msgrcv: 0
2025-11-06 10:01:23,861 - DEBUG - ya.test - _save_outputs: Command (pid 820151) msgsnd: 0
2025-11-06 10:01:23,861 - DEBUG - ya.test - _save_outputs: Command (pid 820151) nivcsw: 3
2025-11-06 10:01:23,861 - DEBUG - ya.test - _save_outputs: Command (pid 820151) nsignals: 0
2025-11-06 10:01:23,861 - DEBUG - ya.test - _save_outputs: Command (pid 820151) nswap: 0
2025-11-06 10:01:23,862 - DEBUG - ya.test - _save_outputs: Command (pid 820151) nvcsw: 13
2025-11-06 10:01:23,862 - DEBUG - ya.test - _save_outputs: Command (pid 820151) oublock: 8
2025-11-06 10:01:23,862 - DEBUG - ya.test - _save_outputs: Command (pid 820151) stime: 0.162846
2025-11-06 10:01:23,862 - DEBUG - ya.test - _save_outputs: Command (pid 820151) utime: 0.115184
2025-11-06 10:01:23,862 - DEBUG - ya.test - _save_outputs: Command (pid 820151) wtime: 0.327
2025-11-06 10:01:23,863 - DEBUG - ya.test - _save_outputs: Command (pid 820151) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ar5i2wnyyy
    Host information: 
        Linux ghrun-ar5i2wnyyy 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-06 10:01:23,863 - DEBUG - ya.test - _save_outputs: Command (pid 820151) errors:

2025-11-06 10:01:23,863 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3
2025-11-06 10:01:25,736 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:25,736 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1347', '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: 7996\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-06 10:01:25,738 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.38
2025-11-06 10:01:25,739 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.38
2025-11-06 10:01:25,739 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1347', '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: 7996\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-06 10:01:25,740 - DEBUG - ya.test - execute: Command pid: 824169
2025-11-06 10:01:26,167 - DEBUG - ya.test - _save_outputs: Command (pid 824169) rc: 0
2025-11-06 10:01:26,167 - DEBUG - ya.test - _save_outputs: Command (pid 824169) elapsed time (sec): 0.4257071018218994
2025-11-06 10:01:26,167 - DEBUG - ya.test - _save_outputs: Command (pid 824169) idrss: 0
2025-11-06 10:01:26,167 - DEBUG - ya.test - _save_outputs: Command (pid 824169) inblock: 0
2025-11-06 10:01:26,168 - DEBUG - ya.test - _save_outputs: Command (pid 824169) isrss: 0
2025-11-06 10:01:26,168 - DEBUG - ya.test - _save_outputs: Command (pid 824169) ixrss: 0
2025-11-06 10:01:26,168 - DEBUG - ya.test - _save_outputs: Command (pid 824169) majflt: 0
2025-11-06 10:01:26,168 - DEBUG - ya.test - _save_outputs: Command (pid 824169) maxrss: 547180
2025-11-06 10:01:26,168 - DEBUG - ya.test - _save_outputs: Command (pid 824169) minflt: 18277
2025-11-06 10:01:26,168 - DEBUG - ya.test - _save_outputs: Command (pid 824169) msgrcv: 0
2025-11-06 10:01:26,168 - DEBUG - ya.test - _save_outputs: Command (pid 824169) msgsnd: 0
2025-11-06 10:01:26,169 - DEBUG - ya.test - _save_outputs: Command (pid 824169) nivcsw: 17
2025-11-06 10:01:26,169 - DEBUG - ya.test - _save_outputs: Command (pid 824169) nsignals: 0
2025-11-06 10:01:26,169 - DEBUG - ya.test - _save_outputs: Command (pid 824169) nswap: 0
2025-11-06 10:01:26,169 - DEBUG - ya.test - _save_outputs: Command (pid 824169) nvcsw: 129
2025-11-06 10:01:26,169 - DEBUG - ya.test - _save_outputs: Command (pid 824169) oublock: 8
2025-11-06 10:01:26,169 - DEBUG - ya.test - _save_outputs: Command (pid 824169) stime: 0.29405
2025-11-06 10:01:26,169 - DEBUG - ya.test - _save_outputs: Command (pid 824169) utime: 0.084705
2025-11-06 10:01:26,170 - DEBUG - ya.test - _save_outputs: Command (pid 824169) wtime: 0.427
2025-11-06 10:01:26,170 - DEBUG - ya.test - _save_outputs: Command (pid 824169) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-06 10:01:26,170 - DEBUG - ya.test - _save_outputs: Command (pid 824169) errors:

2025-11-06 10:01:26,171 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:26,171 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1347', '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-06 10:01:26,172 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.39
2025-11-06 10:01:26,173 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.39
2025-11-06 10:01:26,173 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1347', '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-06 10:01:26,174 - DEBUG - ya.test - execute: Command pid: 824943
2025-11-06 10:01:26,569 - DEBUG - ya.test - _save_outputs: Command (pid 824943) rc: 0
2025-11-06 10:01:26,570 - DEBUG - ya.test - _save_outputs: Command (pid 824943) elapsed time (sec): 0.394634485244751
2025-11-06 10:01:26,570 - DEBUG - ya.test - _save_outputs: Command (pid 824943) idrss: 0
2025-11-06 10:01:26,570 - DEBUG - ya.test - _save_outputs: Command (pid 824943) inblock: 0
2025-11-06 10:01:26,570 - DEBUG - ya.test - _save_outputs: Command (pid 824943) isrss: 0
2025-11-06 10:01:26,571 - DEBUG - ya.test - _save_outputs: Command (pid 824943) ixrss: 0
2025-11-06 10:01:26,571 - DEBUG - ya.test - _save_outputs: Command (pid 824943) majflt: 0
2025-11-06 10:01:26,571 - DEBUG - ya.test - _save_outputs: Command (pid 824943) maxrss: 547176
2025-11-06 10:01:26,571 - DEBUG - ya.test - _save_outputs: Command (pid 824943) minflt: 18277
2025-11-06 10:01:26,571 - DEBUG - ya.test - _save_outputs: Command (pid 824943) msgrcv: 0
2025-11-06 10:01:26,571 - DEBUG - ya.test - _save_outputs: Command (pid 824943) msgsnd: 0
2025-11-06 10:01:26,572 - DEBUG - ya.test - _save_outputs: Command (pid 824943) nivcsw: 33
2025-11-06 10:01:26,572 - DEBUG - ya.test - _save_outputs: Command (pid 824943) nsignals: 0
2025-11-06 10:01:26,572 - DEBUG - ya.test - _save_outputs: Command (pid 824943) nswap: 0
2025-11-06 10:01:26,572 - DEBUG - ya.test - _save_outputs: Command (pid 824943) nvcsw: 122
2025-11-06 10:01:26,572 - DEBUG - ya.test - _save_outputs: Command (pid 824943) oublock: 8
2025-11-06 10:01:26,572 - DEBUG - ya.test - _save_outputs: Command (pid 824943) stime: 0.21045699999999998
2025-11-06 10:01:26,573 - DEBUG - ya.test - _save_outputs: Command (pid 824943) utime: 0.13156199999999998
2025-11-06 10:01:26,573 - DEBUG - ya.test - _save_outputs: Command (pid 824943) wtime: 0.396
2025-11-06 10:01:26,573 - DEBUG - ya.test - _save_outputs: Command (pid 824943) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-06 10:01:26,573 - DEBUG - ya.test - _save_outputs: Command (pid 824943) errors:

2025-11-06 10:01:26,574 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-06 10:01:26,590 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-06 10:01:26,600 - 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-06 10:01:26,600 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-06 10:01:27,114 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:27,115 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20234
2025-11-06 10:01:27,115 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8875
2025-11-06 10:01:27,115 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25189
2025-11-06 10:01:27,116 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7830
2025-11-06 10:01:27,116 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8194
2025-11-06 10:01:27,116 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:27,117 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_omfga8kn.log --grpc-port=20234 --mon-port=8875 --ic-port=25189
2025-11-06 10:01:27,117 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:27,117 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_omfga8kn.log --grpc-port=20234 --mon-port=8875 --ic-port=25189
2025-11-06 10:01:27,118 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4esq/004cd1/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:1347', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_omfga8kn.log', '--grpc-port=20234', '--mon-port=8875', '--ic-port=25189')' in '/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:27,119 - DEBUG - ya.test - execute: Command pid: 826761
2025-11-06 10:01:27,119 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20234/1
2025-11-06 10:01:27,120 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.40
2025-11-06 10:01:27,121 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.40
2025-11-06 10:01:27,122 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:01:27,122 - DEBUG - ya.test - execute: Command pid: 826769
2025-11-06 10:01:27,487 - DEBUG - ya.test - _save_outputs: Command (pid 826769) rc: 0
2025-11-06 10:01:27,487 - DEBUG - ya.test - _save_outputs: Command (pid 826769) elapsed time (sec): 0.36406755447387695
2025-11-06 10:01:27,488 - DEBUG - ya.test - _save_outputs: Command (pid 826769) idrss: 0
2025-11-06 10:01:27,488 - DEBUG - ya.test - _save_outputs: Command (pid 826769) inblock: 0
2025-11-06 10:01:27,488 - DEBUG - ya.test - _save_outputs: Command (pid 826769) isrss: 0
2025-11-06 10:01:27,488 - DEBUG - ya.test - _save_outputs: Command (pid 826769) ixrss: 0
2025-11-06 10:01:27,488 - DEBUG - ya.test - _save_outputs: Command (pid 826769) majflt: 0
2025-11-06 10:01:27,489 - DEBUG - ya.test - _save_outputs: Command (pid 826769) maxrss: 538864
2025-11-06 10:01:27,489 - DEBUG - ya.test - _save_outputs: Command (pid 826769) minflt: 16280
2025-11-06 10:01:27,489 - DEBUG - ya.test - _save_outputs: Command (pid 826769) msgrcv: 0
2025-11-06 10:01:27,489 - DEBUG - ya.test - _save_outputs: Command (pid 826769) msgsnd: 0
2025-11-06 10:01:27,489 - DEBUG - ya.test - _save_outputs: Command (pid 826769) nivcsw: 41
2025-11-06 10:01:27,489 - DEBUG - ya.test - _save_outputs: Command (pid 826769) nsignals: 0
2025-11-06 10:01:27,490 - DEBUG - ya.test - _save_outputs: Command (pid 826769) nswap: 0
2025-11-06 10:01:27,490 - DEBUG - ya.test - _save_outputs: Command (pid 826769) nvcsw: 13
2025-11-06 10:01:27,490 - DEBUG - ya.test - _save_outputs: Command (pid 826769) oublock: 8
2025-11-06 10:01:27,490 - DEBUG - ya.test - _save_outputs: Command (pid 826769) stime: 0.21894899999999998
2025-11-06 10:01:27,490 - DEBUG - ya.test - _save_outputs: Command (pid 826769) utime: 0.093835
2025-11-06 10:01:27,490 - DEBUG - ya.test - _save_outputs: Command (pid 826769) wtime: 0.365
2025-11-06 10:01:27,491 - DEBUG - ya.test - _save_outputs: Command (pid 826769) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ar5i2wnyyy
    Host information: 
        Linux ghrun-ar5i2wnyyy 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-06 10:01:27,491 - DEBUG - ya.test - _save_outputs: Command (pid 826769) errors:

2025-11-06 10:01:27,491 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3
2025-11-06 10:01:27,491 - 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-06 10:01:27,493 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:27,494 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-06 10:01:27,495 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-06 10:01:27,495 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4914191280 state=pending>
2025-11-06 10:01:27,496 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-06 10:01:27,496 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:27,495 - 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-06 10:01:27,496 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:27,499 - 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-06 10:01:27,502 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:27,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c920819e-e32e-400e-954d-d979f682619a, localhost:1347): creating call state
2025-11-06 10:01:27,503 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c920819e-e32e-400e-954d-d979f682619a, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:27,505 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c920819e-e32e-400e-954d-d979f682619a, localhost:1347): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-11-06 10:01:27,506 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:1347". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-11-06 10:01:27,506 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:29,151 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:29,151 - 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-06 10:01:29,157 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:29,158 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 79e6c054-326a-45c7-9f6d-0e82757762a8, localhost:1347): creating call state
2025-11-06 10:01:29,158 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 79e6c054-326a-45c7-9f6d-0e82757762a8, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:29,160 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 79e6c054-326a-45c7-9f6d-0e82757762a8, localhost:1347): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ar5i2wnyyy.auto.internal" port: 20234 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-06 10:01:29,161 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ar5i2wnyyy.auto.internal:20234, location , ssl: False>]>
2025-11-06 10:01:29,161 - 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-06 10:01:29,167 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:29,167 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,168 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4aa22715-3f28-454b-ab9a-d86a973996f5, ghrun-ar5i2wnyyy.auto.internal:20234): creating call state
2025-11-06 10:01:29,169 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4aa22715-3f28-454b-ab9a-d86a973996f5, ghrun-ar5i2wnyyy.auto.internal:20234): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:29,171 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4aa22715-3f28-454b-ab9a-d86a973996f5, ghrun-ar5i2wnyyy.auto.internal:20234): on response callback started
2025-11-06 10:01:29,172 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4aa22715-3f28-454b-ab9a-d86a973996f5, ghrun-ar5i2wnyyy.auto.internal:20234): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZGIxNTNjNDItNjc2ZTRjY2QtZTA3Njg3NjUtNzljNmNlOGY=" } } } }
2025-11-06 10:01:29,172 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,173 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,173 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 305f91b5-5cab-47d3-994f-d0c825695e43, ghrun-ar5i2wnyyy.auto.internal:20234): creating call state
2025-11-06 10:01:29,174 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4aa22715-3f28-454b-ab9a-d86a973996f5, ghrun-ar5i2wnyyy.auto.internal:20234): on response callback success
2025-11-06 10:01:29,174 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 305f91b5-5cab-47d3-994f-d0c825695e43, ghrun-ar5i2wnyyy.auto.internal:20234): request = { session_id: "ydb://session/3?node_id=50000&id=ZGIxNTNjNDItNjc2ZTRjY2QtZTA3Njg3NjUtNzljNmNlOGY=" 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-06 10:01:29,205 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 305f91b5-5cab-47d3-994f-d0c825695e43, ghrun-ar5i2wnyyy.auto.internal:20234): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:29,206 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,206 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-06 10:01:29,206 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, a8149ecb-b6e3-42fa-8da9-cc66f2d35164, ghrun-ar5i2wnyyy.auto.internal:20234): creating call state
2025-11-06 10:01:29,207 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, a8149ecb-b6e3-42fa-8da9-cc66f2d35164, ghrun-ar5i2wnyyy.auto.internal:20234): request = { session_id: "ydb://session/3?node_id=50000&id=ZGIxNTNjNDItNjc2ZTRjY2QtZTA3Njg3NjUtNzljNmNlOGY=" 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-06 10:01:29,229 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, a8149ecb-b6e3-42fa-8da9-cc66f2d35164, ghrun-ar5i2wnyyy.auto.internal:20234): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:29,230 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,230 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-06 10:01:29,231 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6093fe54-6cf2-4b1e-8e06-4196668cda5b, ghrun-ar5i2wnyyy.auto.internal:20234): creating call state
2025-11-06 10:01:29,232 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6093fe54-6cf2-4b1e-8e06-4196668cda5b, ghrun-ar5i2wnyyy.auto.internal:20234): request = { session_id: "ydb://session/3?node_id=50000&id=ZGIxNTNjNDItNjc2ZTRjY2QtZTA3Njg3NjUtNzljNmNlOGY=" 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-06 10:01:29,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6093fe54-6cf2-4b1e-8e06-4196668cda5b, ghrun-ar5i2wnyyy.auto.internal:20234): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-06 10:01:29,259 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,260 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-06 10:01:29,260 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 395fb531-e09a-43a9-b9ab-718ff672726a, ghrun-ar5i2wnyyy.auto.internal:20234): creating call state
2025-11-06 10:01:29,261 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 395fb531-e09a-43a9-b9ab-718ff672726a, ghrun-ar5i2wnyyy.auto.internal:20234): request = { session_id: "ydb://session/3?node_id=50000&id=ZGIxNTNjNDItNjc2ZTRjY2QtZTA3Njg3NjUtNzljNmNlOGY=" 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-06 10:01:29,296 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 395fb531-e09a-43a9-b9ab-718ff672726a, ghrun-ar5i2wnyyy.auto.internal:20234): 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-06 10:01:29,297 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,297 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-06 10:01:29,298 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 6e122252-1dec-488e-9aeb-6f094e71ef51, ghrun-ar5i2wnyyy.auto.internal:20234): creating call state
2025-11-06 10:01:29,298 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 6e122252-1dec-488e-9aeb-6f094e71ef51, ghrun-ar5i2wnyyy.auto.internal:20234): request = { session_id: "ydb://session/3?node_id=50000&id=ZGIxNTNjNDItNjc2ZTRjY2QtZTA3Njg3NjUtNzljNmNlOGY=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-06 10:01:29,313 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 6e122252-1dec-488e-9aeb-6f094e71ef51, ghrun-ar5i2wnyyy.auto.internal:20234): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:29,313 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,313 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-06 10:01:29,314 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-06 10:01:29,314 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-06 10:01:29,314 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-06 10:01:29,315 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4914193530>, reason: session-pool-terminated
2025-11-06 10:01:29,315 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4914193530> is no longer active. Current active count 0.
2025-11-06 10:01:29,315 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c233b133-729b-496f-a9d5-e1a05efd95a9, ghrun-ar5i2wnyyy.auto.internal:20234): creating call state
2025-11-06 10:01:29,316 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c233b133-729b-496f-a9d5-e1a05efd95a9, ghrun-ar5i2wnyyy.auto.internal:20234): request = { session_id: "ydb://session/3?node_id=50000&id=ZGIxNTNjNDItNjc2ZTRjY2QtZTA3Njg3NjUtNzljNmNlOGY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:29,317 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4914193530>
2025-11-06 10:01:29,317 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, c233b133-729b-496f-a9d5-e1a05efd95a9, ghrun-ar5i2wnyyy.auto.internal:20234): on response callback started
2025-11-06 10:01:29,319 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-06 10:01:29,320 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c233b133-729b-496f-a9d5-e1a05efd95a9, ghrun-ar5i2wnyyy.auto.internal:20234): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:29,320 - DEBUG - ydb.topic - close: Close topic client
2025-11-06 10:01:29,321 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, c233b133-729b-496f-a9d5-e1a05efd95a9, ghrun-ar5i2wnyyy.auto.internal:20234): on response callback success
2025-11-06 10:01:29,321 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-06 10:01:29,321 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:29,360 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ar5i2wnyyy.auto.internal:20234
2025-11-06 10:01:29,369 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-06 10:01:29,369 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-06 10:01:29,370 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-06 10:01:29,370 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-06 10:01:29,374 - 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=1762423289372421&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-06 10:01:29,374 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-06 10:01:29,878 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-06 10:01:29,879 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-06 10:01:29,882 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-06 10:01:30,882 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:20234/1
2025-11-06 10:01:30,883 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 0
2025-11-06 10:01:30,883 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-06 10:01:31,399 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:31,400 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20700
2025-11-06 10:01:31,400 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1861
2025-11-06 10:01:31,400 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9375
2025-11-06 10:01:31,401 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14370
2025-11-06 10:01:31,401 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23506
2025-11-06 10:01:31,402 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:31,402 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_ls3a3598.log --grpc-port=20700 --mon-port=1861 --ic-port=9375
2025-11-06 10:01:31,402 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:31,402 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_ls3a3598.log --grpc-port=20700 --mon-port=1861 --ic-port=9375
2025-11-06 10:01:31,403 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4esq/004cd1/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:1347', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_ls3a3598.log', '--grpc-port=20700', '--mon-port=1861', '--ic-port=9375')' in '/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:31,405 - DEBUG - ya.test - execute: Command pid: 833782
2025-11-06 10:01:31,405 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20700/2
2025-11-06 10:01:31,406 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.41
2025-11-06 10:01:31,407 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.41
2025-11-06 10:01:31,408 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:01:31,409 - DEBUG - ya.test - execute: Command pid: 833800
2025-11-06 10:01:31,777 - DEBUG - ya.test - _save_outputs: Command (pid 833800) rc: 0
2025-11-06 10:01:31,778 - DEBUG - ya.test - _save_outputs: Command (pid 833800) elapsed time (sec): 0.3682594299316406
2025-11-06 10:01:31,778 - DEBUG - ya.test - _save_outputs: Command (pid 833800) idrss: 0
2025-11-06 10:01:31,778 - DEBUG - ya.test - _save_outputs: Command (pid 833800) inblock: 0
2025-11-06 10:01:31,778 - DEBUG - ya.test - _save_outputs: Command (pid 833800) isrss: 0
2025-11-06 10:01:31,778 - DEBUG - ya.test - _save_outputs: Command (pid 833800) ixrss: 0
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) majflt: 0
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) maxrss: 538764
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) minflt: 16133
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) msgrcv: 0
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) msgsnd: 0
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) nivcsw: 7
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) nsignals: 0
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) nswap: 0
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) nvcsw: 13
2025-11-06 10:01:31,779 - DEBUG - ya.test - _save_outputs: Command (pid 833800) oublock: 8
2025-11-06 10:01:31,780 - DEBUG - ya.test - _save_outputs: Command (pid 833800) stime: 0.22211699999999998
2025-11-06 10:01:31,780 - DEBUG - ya.test - _save_outputs: Command (pid 833800) utime: 0.097606
2025-11-06 10:01:31,780 - DEBUG - ya.test - _save_outputs: Command (pid 833800) wtime: 0.37
2025-11-06 10:01:31,780 - DEBUG - ya.test - _save_outputs: Command (pid 833800) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ar5i2wnyyy
    Host information: 
        Linux ghrun-ar5i2wnyyy 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-06 10:01:31,780 - DEBUG - ya.test - _save_outputs: Command (pid 833800) errors:

2025-11-06 10:01:31,781 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3
2025-11-06 10:01:31,781 - 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-06 10:01:31,783 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:31,784 - 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-06 10:01:31,784 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-06 10:01:31,787 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-06 10:01:31,787 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4914199ff0 state=pending>
2025-11-06 10:01:31,788 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-06 10:01:31,788 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:31,788 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:31,789 - 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-06 10:01:31,790 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:31,790 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c07082ae-c4e2-44e0-a764-4823842156cd, localhost:1347): creating call state
2025-11-06 10:01:31,790 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c07082ae-c4e2-44e0-a764-4823842156cd, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:31,792 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c07082ae-c4e2-44e0-a764-4823842156cd, localhost:1347): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-11-06 10:01:31,793 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:1347". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-11-06 10:01:31,793 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:33,778 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:33,778 - 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-06 10:01:33,782 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:33,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, ec760977-a3e9-4585-9c4f-357451ed18a8, localhost:1347): creating call state
2025-11-06 10:01:33,783 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, ec760977-a3e9-4585-9c4f-357451ed18a8, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:33,785 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, ec760977-a3e9-4585-9c4f-357451ed18a8, localhost:1347): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ar5i2wnyyy.auto.internal" port: 20700 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-06 10:01:33,786 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ar5i2wnyyy.auto.internal:20700, location , ssl: False>]>
2025-11-06 10:01:33,786 - 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-06 10:01:33,794 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:33,795 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:33,795 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f3155835-5b60-4d69-9a21-1eba0cb9e3e2, ghrun-ar5i2wnyyy.auto.internal:20700): creating call state
2025-11-06 10:01:33,796 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f3155835-5b60-4d69-9a21-1eba0cb9e3e2, ghrun-ar5i2wnyyy.auto.internal:20700): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:33,798 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f3155835-5b60-4d69-9a21-1eba0cb9e3e2, ghrun-ar5i2wnyyy.auto.internal:20700): on response callback started
2025-11-06 10:01:33,799 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f3155835-5b60-4d69-9a21-1eba0cb9e3e2, ghrun-ar5i2wnyyy.auto.internal:20700): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=Yjk0MGU1MjItNzE3ZDMyNGUtNjkzZTU3ODAtYTViOGQyN2Y=" } } } }
2025-11-06 10:01:33,799 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:33,799 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:33,800 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, ad6b5335-1a58-4937-a0ec-8bbfbcb24b3e, ghrun-ar5i2wnyyy.auto.internal:20700): creating call state
2025-11-06 10:01:33,800 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, f3155835-5b60-4d69-9a21-1eba0cb9e3e2, ghrun-ar5i2wnyyy.auto.internal:20700): on response callback success
2025-11-06 10:01:33,801 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, ad6b5335-1a58-4937-a0ec-8bbfbcb24b3e, ghrun-ar5i2wnyyy.auto.internal:20700): request = { session_id: "ydb://session/3?node_id=50001&id=Yjk0MGU1MjItNzE3ZDMyNGUtNjkzZTU3ODAtYTViOGQyN2Y=" 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-06 10:01:34,737 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, ad6b5335-1a58-4937-a0ec-8bbfbcb24b3e, ghrun-ar5i2wnyyy.auto.internal:20700): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:34,737 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:34,737 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-06 10:01:34,738 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 07ca1c93-349e-4a3e-ba12-e1eb4dbdacd9, ghrun-ar5i2wnyyy.auto.internal:20700): creating call state
2025-11-06 10:01:34,738 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 07ca1c93-349e-4a3e-ba12-e1eb4dbdacd9, ghrun-ar5i2wnyyy.auto.internal:20700): request = { session_id: "ydb://session/3?node_id=50001&id=Yjk0MGU1MjItNzE3ZDMyNGUtNjkzZTU3ODAtYTViOGQyN2Y=" 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-06 10:01:34,771 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 07ca1c93-349e-4a3e-ba12-e1eb4dbdacd9, ghrun-ar5i2wnyyy.auto.internal:20700): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:34,771 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:34,772 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-06 10:01:34,772 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9407bc22-25d9-4d3b-8087-cf49be5638a7, ghrun-ar5i2wnyyy.auto.internal:20700): creating call state
2025-11-06 10:01:34,773 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9407bc22-25d9-4d3b-8087-cf49be5638a7, ghrun-ar5i2wnyyy.auto.internal:20700): request = { session_id: "ydb://session/3?node_id=50001&id=Yjk0MGU1MjItNzE3ZDMyNGUtNjkzZTU3ODAtYTViOGQyN2Y=" 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-06 10:01:34,859 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9407bc22-25d9-4d3b-8087-cf49be5638a7, ghrun-ar5i2wnyyy.auto.internal:20700): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-06 10:01:34,860 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:34,860 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-06 10:01:34,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c32f1b51-20a4-4ee6-8026-517ccc2f5684, ghrun-ar5i2wnyyy.auto.internal:20700): creating call state
2025-11-06 10:01:34,861 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c32f1b51-20a4-4ee6-8026-517ccc2f5684, ghrun-ar5i2wnyyy.auto.internal:20700): request = { session_id: "ydb://session/3?node_id=50001&id=Yjk0MGU1MjItNzE3ZDMyNGUtNjkzZTU3ODAtYTViOGQyN2Y=" 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-06 10:01:34,893 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c32f1b51-20a4-4ee6-8026-517ccc2f5684, ghrun-ar5i2wnyyy.auto.internal:20700): 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-06 10:01:34,893 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:34,894 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-06 10:01:34,894 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, f19ee850-3454-4d54-b727-c6f6def4f92d, ghrun-ar5i2wnyyy.auto.internal:20700): creating call state
2025-11-06 10:01:34,894 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, f19ee850-3454-4d54-b727-c6f6def4f92d, ghrun-ar5i2wnyyy.auto.internal:20700): request = { session_id: "ydb://session/3?node_id=50001&id=Yjk0MGU1MjItNzE3ZDMyNGUtNjkzZTU3ODAtYTViOGQyN2Y=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-06 10:01:34,909 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, f19ee850-3454-4d54-b727-c6f6def4f92d, ghrun-ar5i2wnyyy.auto.internal:20700): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:34,911 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:34,911 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-06 10:01:34,911 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-06 10:01:34,911 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-06 10:01:34,912 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-06 10:01:34,912 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4914199820>, reason: session-pool-terminated
2025-11-06 10:01:34,912 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4914199820> is no longer active. Current active count 0.
2025-11-06 10:01:34,912 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, aceba827-588f-455c-90e3-e04acbd24e63, ghrun-ar5i2wnyyy.auto.internal:20700): creating call state
2025-11-06 10:01:34,913 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, aceba827-588f-455c-90e3-e04acbd24e63, ghrun-ar5i2wnyyy.auto.internal:20700): request = { session_id: "ydb://session/3?node_id=50001&id=Yjk0MGU1MjItNzE3ZDMyNGUtNjkzZTU3ODAtYTViOGQyN2Y=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:34,914 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4914199820>
2025-11-06 10:01:34,914 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-06 10:01:34,914 - DEBUG - ydb.topic - close: Close topic client
2025-11-06 10:01:34,914 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-06 10:01:34,915 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, aceba827-588f-455c-90e3-e04acbd24e63, ghrun-ar5i2wnyyy.auto.internal:20700): on response callback started
2025-11-06 10:01:34,915 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:34,915 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ar5i2wnyyy.auto.internal:20700
2025-11-06 10:01:34,916 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, aceba827-588f-455c-90e3-e04acbd24e63, ghrun-ar5i2wnyyy.auto.internal:20700): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:34,916 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-06 10:01:34,917 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, aceba827-588f-455c-90e3-e04acbd24e63, ghrun-ar5i2wnyyy.auto.internal:20700): on response callback success
2025-11-06 10:01:34,917 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-06 10:01:34,918 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-06 10:01:34,918 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-06 10:01:34,923 - 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=1762423294920997&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-06 10:01:34,923 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-06 10:01:35,427 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-06 10:01:35,428 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-06 10:01:35,435 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-06 10:01:36,435 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:20700/2
2025-11-06 10:01:36,436 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 1
2025-11-06 10:01:36,436 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-06 10:01:36,948 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:36,949 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5688
2025-11-06 10:01:36,949 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15772
2025-11-06 10:01:36,950 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7806
2025-11-06 10:01:36,950 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15974
2025-11-06 10:01:36,950 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28493
2025-11-06 10:01:36,951 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:36,951 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_5kyayet6.log --grpc-port=5688 --mon-port=15772 --ic-port=7806
2025-11-06 10:01:36,951 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:36,951 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_5kyayet6.log --grpc-port=5688 --mon-port=15772 --ic-port=7806
2025-11-06 10:01:36,952 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4esq/004cd1/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:1347', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_5kyayet6.log', '--grpc-port=5688', '--mon-port=15772', '--ic-port=7806')' in '/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:36,953 - DEBUG - ya.test - execute: Command pid: 843593
2025-11-06 10:01:36,954 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5688/3
2025-11-06 10:01:36,955 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.42
2025-11-06 10:01:36,955 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.42
2025-11-06 10:01:36,955 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:01:36,956 - DEBUG - ya.test - execute: Command pid: 843606
2025-11-06 10:01:37,384 - DEBUG - ya.test - _save_outputs: Command (pid 843606) rc: 0
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) elapsed time (sec): 0.42797040939331055
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) idrss: 0
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) inblock: 0
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) isrss: 0
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) ixrss: 0
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) majflt: 2
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) maxrss: 538764
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) minflt: 16377
2025-11-06 10:01:37,385 - DEBUG - ya.test - _save_outputs: Command (pid 843606) msgrcv: 0
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) msgsnd: 0
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) nivcsw: 30
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) nsignals: 0
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) nswap: 0
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) nvcsw: 22
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) oublock: 8
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) stime: 0.283943
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) utime: 0.08683199999999999
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) wtime: 0.429
2025-11-06 10:01:37,386 - DEBUG - ya.test - _save_outputs: Command (pid 843606) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ar5i2wnyyy
    Host information: 
        Linux ghrun-ar5i2wnyyy 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-06 10:01:37,387 - DEBUG - ya.test - _save_outputs: Command (pid 843606) errors:

2025-11-06 10:01:37,387 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 3 version:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3
2025-11-06 10:01:37,387 - 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-06 10:01:37,392 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:37,393 - 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-06 10:01:37,397 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-06 10:01:37,397 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-06 10:01:37,398 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f49140e2b80 state=pending>
2025-11-06 10:01:37,398 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-06 10:01:37,398 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:37,398 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:37,399 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:37,399 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, dc19ecbe-cdbb-45a5-9d0b-24f5ea76cad2, localhost:1347): creating call state
2025-11-06 10:01:37,400 - 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-06 10:01:37,400 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, dc19ecbe-cdbb-45a5-9d0b-24f5ea76cad2, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:37,402 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, dc19ecbe-cdbb-45a5-9d0b-24f5ea76cad2, localhost:1347): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-11-06 10:01:37,402 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:1347". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-11-06 10:01:37,403 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:38,987 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:38,987 - 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-06 10:01:38,993 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:38,993 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, aa994dad-30de-459d-bc52-1fbb8e4433c4, localhost:1347): creating call state
2025-11-06 10:01:38,994 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, aa994dad-30de-459d-bc52-1fbb8e4433c4, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:38,996 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, aa994dad-30de-459d-bc52-1fbb8e4433c4, localhost:1347): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ar5i2wnyyy.auto.internal" port: 5688 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-06 10:01:38,997 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ar5i2wnyyy.auto.internal:5688, location , ssl: False>]>
2025-11-06 10:01:38,997 - 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-06 10:01:39,004 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:39,004 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,005 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6bb0b78a-0356-46b3-b927-13932b2ba5a6, ghrun-ar5i2wnyyy.auto.internal:5688): creating call state
2025-11-06 10:01:39,006 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6bb0b78a-0356-46b3-b927-13932b2ba5a6, ghrun-ar5i2wnyyy.auto.internal:5688): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:39,008 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6bb0b78a-0356-46b3-b927-13932b2ba5a6, ghrun-ar5i2wnyyy.auto.internal:5688): on response callback started
2025-11-06 10:01:39,009 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6bb0b78a-0356-46b3-b927-13932b2ba5a6, ghrun-ar5i2wnyyy.auto.internal:5688): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=NTg0MzBhYTgtNzFlODE0MzEtOTM2ZTU3Y2YtOGQwMjI4ZTA=" } } } }
2025-11-06 10:01:39,009 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,009 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,010 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 6fa04750-f8c3-4d8b-ac12-f21bf6f10939, ghrun-ar5i2wnyyy.auto.internal:5688): creating call state
2025-11-06 10:01:39,010 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6bb0b78a-0356-46b3-b927-13932b2ba5a6, ghrun-ar5i2wnyyy.auto.internal:5688): on response callback success
2025-11-06 10:01:39,011 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 6fa04750-f8c3-4d8b-ac12-f21bf6f10939, ghrun-ar5i2wnyyy.auto.internal:5688): request = { session_id: "ydb://session/3?node_id=50002&id=NTg0MzBhYTgtNzFlODE0MzEtOTM2ZTU3Y2YtOGQwMjI4ZTA=" 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-06 10:01:39,056 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 6fa04750-f8c3-4d8b-ac12-f21bf6f10939, ghrun-ar5i2wnyyy.auto.internal:5688): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:39,057 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,057 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-06 10:01:39,058 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 433fbdc4-b115-4f7e-bfc1-840bb5317a63, ghrun-ar5i2wnyyy.auto.internal:5688): creating call state
2025-11-06 10:01:39,058 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 433fbdc4-b115-4f7e-bfc1-840bb5317a63, ghrun-ar5i2wnyyy.auto.internal:5688): request = { session_id: "ydb://session/3?node_id=50002&id=NTg0MzBhYTgtNzFlODE0MzEtOTM2ZTU3Y2YtOGQwMjI4ZTA=" 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-06 10:01:39,081 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 433fbdc4-b115-4f7e-bfc1-840bb5317a63, ghrun-ar5i2wnyyy.auto.internal:5688): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:39,082 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,082 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-06 10:01:39,083 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0077ce74-7f09-4efa-9cc5-cf162f532c0b, ghrun-ar5i2wnyyy.auto.internal:5688): creating call state
2025-11-06 10:01:39,083 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0077ce74-7f09-4efa-9cc5-cf162f532c0b, ghrun-ar5i2wnyyy.auto.internal:5688): request = { session_id: "ydb://session/3?node_id=50002&id=NTg0MzBhYTgtNzFlODE0MzEtOTM2ZTU3Y2YtOGQwMjI4ZTA=" 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-06 10:01:39,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0077ce74-7f09-4efa-9cc5-cf162f532c0b, ghrun-ar5i2wnyyy.auto.internal:5688): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-06 10:01:39,151 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,152 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-06 10:01:39,152 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, c70de629-e220-4d64-a834-ed0299629953, ghrun-ar5i2wnyyy.auto.internal:5688): creating call state
2025-11-06 10:01:39,153 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, c70de629-e220-4d64-a834-ed0299629953, ghrun-ar5i2wnyyy.auto.internal:5688): request = { session_id: "ydb://session/3?node_id=50002&id=NTg0MzBhYTgtNzFlODE0MzEtOTM2ZTU3Y2YtOGQwMjI4ZTA=" 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-06 10:01:39,187 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, c70de629-e220-4d64-a834-ed0299629953, ghrun-ar5i2wnyyy.auto.internal:5688): 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-06 10:01:39,188 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,188 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-06 10:01:39,188 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, b70c049c-5d22-4b48-90d5-5b085928dcc1, ghrun-ar5i2wnyyy.auto.internal:5688): creating call state
2025-11-06 10:01:39,189 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, b70c049c-5d22-4b48-90d5-5b085928dcc1, ghrun-ar5i2wnyyy.auto.internal:5688): request = { session_id: "ydb://session/3?node_id=50002&id=NTg0MzBhYTgtNzFlODE0MzEtOTM2ZTU3Y2YtOGQwMjI4ZTA=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-06 10:01:39,208 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, b70c049c-5d22-4b48-90d5-5b085928dcc1, ghrun-ar5i2wnyyy.auto.internal:5688): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:39,208 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,208 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-06 10:01:39,209 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-06 10:01:39,209 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-06 10:01:39,209 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-06 10:01:39,210 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f49140e38a0>, reason: session-pool-terminated
2025-11-06 10:01:39,210 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f49140e38a0> is no longer active. Current active count 0.
2025-11-06 10:01:39,210 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 14188299-5880-43d1-aa6f-f44976f4ab71, ghrun-ar5i2wnyyy.auto.internal:5688): creating call state
2025-11-06 10:01:39,211 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 14188299-5880-43d1-aa6f-f44976f4ab71, ghrun-ar5i2wnyyy.auto.internal:5688): request = { session_id: "ydb://session/3?node_id=50002&id=NTg0MzBhYTgtNzFlODE0MzEtOTM2ZTU3Y2YtOGQwMjI4ZTA=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:39,212 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f49140e38a0>
2025-11-06 10:01:39,212 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-06 10:01:39,212 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 14188299-5880-43d1-aa6f-f44976f4ab71, ghrun-ar5i2wnyyy.auto.internal:5688): on response callback started
2025-11-06 10:01:39,213 - DEBUG - ydb.topic - close: Close topic client
2025-11-06 10:01:39,213 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 14188299-5880-43d1-aa6f-f44976f4ab71, ghrun-ar5i2wnyyy.auto.internal:5688): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:39,213 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-06 10:01:39,213 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 14188299-5880-43d1-aa6f-f44976f4ab71, ghrun-ar5i2wnyyy.auto.internal:5688): on response callback success
2025-11-06 10:01:39,214 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:39,214 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ar5i2wnyyy.auto.internal:5688
2025-11-06 10:01:39,215 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-06 10:01:39,215 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-06 10:01:39,215 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-06 10:01:39,215 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-06 10:01:39,219 - 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=1762423299217691&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-06 10:01:39,220 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-06 10:01:39,723 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-06 10:01:39,723 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-06 10:01:39,725 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-06 10:01:40,726 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5688/3
2025-11-06 10:01:40,726 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 2
2025-11-06 10:01:40,727 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-11-06 10:01:41,238 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd
2025-11-06 10:01:41,238 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5246
2025-11-06 10:01:41,238 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14030
2025-11-06 10:01:41,239 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32675
2025-11-06 10:01:41,239 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2167
2025-11-06 10:01:41,239 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5415
2025-11-06 10:01:41,240 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:41,240 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_6jl37ro4.log --grpc-port=5246 --mon-port=14030 --ic-port=32675
2025-11-06 10:01:41,240 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:41,241 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:1347 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_6jl37ro4.log --grpc-port=5246 --mon-port=14030 --ic-port=32675
2025-11-06 10:01:41,241 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4esq/004cd1/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:1347', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/4esq/004cd1/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_6jl37ro4.log', '--grpc-port=5246', '--mon-port=14030', '--ic-port=32675')' in '/home/runner/.ya/build/build_root/4esq/004cd1/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-06 10:01:41,243 - DEBUG - ya.test - execute: Command pid: 852652
2025-11-06 10:01:41,243 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5246/4
2025-11-06 10:01:41,245 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.43
2025-11-06 10:01:41,246 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.43
2025-11-06 10:01:41,246 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4esq/004cd1/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:01:41,247 - DEBUG - ya.test - execute: Command pid: 852666
2025-11-06 10:01:41,833 - DEBUG - ya.test - _save_outputs: Command (pid 852666) rc: 0
2025-11-06 10:01:41,833 - DEBUG - ya.test - _save_outputs: Command (pid 852666) elapsed time (sec): 0.5850694179534912
2025-11-06 10:01:41,834 - DEBUG - ya.test - _save_outputs: Command (pid 852666) idrss: 0
2025-11-06 10:01:41,834 - DEBUG - ya.test - _save_outputs: Command (pid 852666) inblock: 0
2025-11-06 10:01:41,834 - DEBUG - ya.test - _save_outputs: Command (pid 852666) isrss: 0
2025-11-06 10:01:41,834 - DEBUG - ya.test - _save_outputs: Command (pid 852666) ixrss: 0
2025-11-06 10:01:41,834 - DEBUG - ya.test - _save_outputs: Command (pid 852666) majflt: 0
2025-11-06 10:01:41,835 - DEBUG - ya.test - _save_outputs: Command (pid 852666) maxrss: 538764
2025-11-06 10:01:41,835 - DEBUG - ya.test - _save_outputs: Command (pid 852666) minflt: 16133
2025-11-06 10:01:41,835 - DEBUG - ya.test - _save_outputs: Command (pid 852666) msgrcv: 0
2025-11-06 10:01:41,835 - DEBUG - ya.test - _save_outputs: Command (pid 852666) msgsnd: 0
2025-11-06 10:01:41,835 - DEBUG - ya.test - _save_outputs: Command (pid 852666) nivcsw: 6
2025-11-06 10:01:41,835 - DEBUG - ya.test - _save_outputs: Command (pid 852666) nsignals: 0
2025-11-06 10:01:41,836 - DEBUG - ya.test - _save_outputs: Command (pid 852666) nswap: 0
2025-11-06 10:01:41,836 - DEBUG - ya.test - _save_outputs: Command (pid 852666) nvcsw: 13
2025-11-06 10:01:41,836 - DEBUG - ya.test - _save_outputs: Command (pid 852666) oublock: 8
2025-11-06 10:01:41,836 - DEBUG - ya.test - _save_outputs: Command (pid 852666) stime: 0.413543
2025-11-06 10:01:41,836 - DEBUG - ya.test - _save_outputs: Command (pid 852666) utime: 0.12326699999999999
2025-11-06 10:01:41,837 - DEBUG - ya.test - _save_outputs: Command (pid 852666) wtime: 0.587
2025-11-06 10:01:41,837 - DEBUG - ya.test - _save_outputs: Command (pid 852666) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ar5i2wnyyy
    Host information: 
        Linux ghrun-ar5i2wnyyy 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-06 10:01:41,837 - DEBUG - ya.test - _save_outputs: Command (pid 852666) errors:

2025-11-06 10:01:41,837 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 4 version:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3
2025-11-06 10:01:41,838 - 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-06 10:01:41,839 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:41,840 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-11-06 10:01:41,840 - 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-06 10:01:41,841 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-06 10:01:41,844 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f48fefcbda0 state=pending>
2025-11-06 10:01:41,845 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-06 10:01:41,845 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:41,846 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:41,846 - 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-06 10:01:41,847 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:41,847 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 4a15f74e-46f5-4a1d-967f-99c6e07816ec, localhost:1347): creating call state
2025-11-06 10:01:41,847 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 4a15f74e-46f5-4a1d-967f-99c6e07816ec, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:41,849 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 4a15f74e-46f5-4a1d-967f-99c6e07816ec, localhost:1347): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-11-06 10:01:41,850 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:1347". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-11-06 10:01:41,850 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:43,090 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:01:43,090 - 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-06 10:01:43,094 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-11-06 10:01:43,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 33abe993-0856-4dd1-948d-bac532512d39, localhost:1347): creating call state
2025-11-06 10:01:43,095 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 33abe993-0856-4dd1-948d-bac532512d39, localhost:1347): request = { database: "/Root/users/database" }
2025-11-06 10:01:43,097 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 33abe993-0856-4dd1-948d-bac532512d39, localhost:1347): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ar5i2wnyyy.auto.internal" port: 5246 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-06 10:01:43,098 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ar5i2wnyyy.auto.internal:5246, location , ssl: False>]>
2025-11-06 10:01:43,098 - 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-06 10:01:43,105 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:43,106 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,107 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d058baa1-b35a-4f0e-b27a-45dc8676e764, ghrun-ar5i2wnyyy.auto.internal:5246): creating call state
2025-11-06 10:01:43,107 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d058baa1-b35a-4f0e-b27a-45dc8676e764, ghrun-ar5i2wnyyy.auto.internal:5246): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:43,110 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d058baa1-b35a-4f0e-b27a-45dc8676e764, ghrun-ar5i2wnyyy.auto.internal:5246): on response callback started
2025-11-06 10:01:43,110 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d058baa1-b35a-4f0e-b27a-45dc8676e764, ghrun-ar5i2wnyyy.auto.internal:5246): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=YjFhZmM4YTQtNjgxMDQ4MDUtOGE4NmFjZjgtZTY3NzUxYWQ=" } } } }
2025-11-06 10:01:43,111 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,111 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,111 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 9feb760f-71cf-4d0c-bc14-27231fd366f3, ghrun-ar5i2wnyyy.auto.internal:5246): creating call state
2025-11-06 10:01:43,112 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d058baa1-b35a-4f0e-b27a-45dc8676e764, ghrun-ar5i2wnyyy.auto.internal:5246): on response callback success
2025-11-06 10:01:43,113 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 9feb760f-71cf-4d0c-bc14-27231fd366f3, ghrun-ar5i2wnyyy.auto.internal:5246): request = { session_id: "ydb://session/3?node_id=50003&id=YjFhZmM4YTQtNjgxMDQ4MDUtOGE4NmFjZjgtZTY3NzUxYWQ=" 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-06 10:01:43,144 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 9feb760f-71cf-4d0c-bc14-27231fd366f3, ghrun-ar5i2wnyyy.auto.internal:5246): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:43,144 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,144 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-11-06 10:01:43,145 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, bae932d8-024d-4725-896e-eb8adeebb182, ghrun-ar5i2wnyyy.auto.internal:5246): creating call state
2025-11-06 10:01:43,146 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, bae932d8-024d-4725-896e-eb8adeebb182, ghrun-ar5i2wnyyy.auto.internal:5246): request = { session_id: "ydb://session/3?node_id=50003&id=YjFhZmM4YTQtNjgxMDQ4MDUtOGE4NmFjZjgtZTY3NzUxYWQ=" 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-06 10:01:43,165 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, bae932d8-024d-4725-896e-eb8adeebb182, ghrun-ar5i2wnyyy.auto.internal:5246): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:43,166 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,166 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-11-06 10:01:43,167 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 94067486-13ae-4419-a0ba-ff0555035258, ghrun-ar5i2wnyyy.auto.internal:5246): creating call state
2025-11-06 10:01:43,167 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 94067486-13ae-4419-a0ba-ff0555035258, ghrun-ar5i2wnyyy.auto.internal:5246): request = { session_id: "ydb://session/3?node_id=50003&id=YjFhZmM4YTQtNjgxMDQ4MDUtOGE4NmFjZjgtZTY3NzUxYWQ=" 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-06 10:01:43,296 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 94067486-13ae-4419-a0ba-ff0555035258, ghrun-ar5i2wnyyy.auto.internal:5246): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-06 10:01:43,298 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,298 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-11-06 10:01:43,299 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5402d1cc-b591-49be-b2d1-5d107a19247a, ghrun-ar5i2wnyyy.auto.internal:5246): creating call state
2025-11-06 10:01:43,299 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5402d1cc-b591-49be-b2d1-5d107a19247a, ghrun-ar5i2wnyyy.auto.internal:5246): request = { session_id: "ydb://session/3?node_id=50003&id=YjFhZmM4YTQtNjgxMDQ4MDUtOGE4NmFjZjgtZTY3NzUxYWQ=" 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-06 10:01:43,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5402d1cc-b591-49be-b2d1-5d107a19247a, ghrun-ar5i2wnyyy.auto.internal:5246): 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-06 10:01:43,330 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,331 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-11-06 10:01:43,331 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, b35e6015-724d-408b-8187-7a22de98b1a8, ghrun-ar5i2wnyyy.auto.internal:5246): creating call state
2025-11-06 10:01:43,331 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, b35e6015-724d-408b-8187-7a22de98b1a8, ghrun-ar5i2wnyyy.auto.internal:5246): request = { session_id: "ydb://session/3?node_id=50003&id=YjFhZmM4YTQtNjgxMDQ4MDUtOGE4NmFjZjgtZTY3NzUxYWQ=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-06 10:01:43,346 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, b35e6015-724d-408b-8187-7a22de98b1a8, ghrun-ar5i2wnyyy.auto.internal:5246): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:43,347 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,347 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-06 10:01:43,347 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-06 10:01:43,347 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-06 10:01:43,348 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-06 10:01:43,348 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4914823c10>, reason: session-pool-terminated
2025-11-06 10:01:43,349 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4914823c10> is no longer active. Current active count 0.
2025-11-06 10:01:43,349 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, be30f579-2e0e-44f4-ad2e-554d8ad13ac9, ghrun-ar5i2wnyyy.auto.internal:5246): creating call state
2025-11-06 10:01:43,349 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, be30f579-2e0e-44f4-ad2e-554d8ad13ac9, ghrun-ar5i2wnyyy.auto.internal:5246): request = { session_id: "ydb://session/3?node_id=50003&id=YjFhZmM4YTQtNjgxMDQ4MDUtOGE4NmFjZjgtZTY3NzUxYWQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-06 10:01:43,350 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4914823c10>
2025-11-06 10:01:43,351 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-06 10:01:43,351 - DEBUG - ydb.topic - close: Close topic client
2025-11-06 10:01:43,351 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-06 10:01:43,351 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, be30f579-2e0e-44f4-ad2e-554d8ad13ac9, ghrun-ar5i2wnyyy.auto.internal:5246): on response callback started
2025-11-06 10:01:43,352 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1347
2025-11-06 10:01:43,352 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ar5i2wnyyy.auto.internal:5246
2025-11-06 10:01:43,352 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, be30f579-2e0e-44f4-ad2e-554d8ad13ac9, ghrun-ar5i2wnyyy.auto.internal:5246): response = { operation { ready: true status: SUCCESS } }
2025-11-06 10:01:43,353 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-06 10:01:43,354 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, be30f579-2e0e-44f4-ad2e-554d8ad13ac9, ghrun-ar5i2wnyyy.auto.internal:5246): on response callback success
2025-11-06 10:01:43,354 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-11-06 10:01:43,355 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-11-06 10:01:43,355 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-11-06 10:01:43,360 - 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=1762423303357730&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-06 10:01:43,360 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-11-06 10:01:43,864 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-11-06 10:01:43,867 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-11-06 10:01:43,871 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-11-06 10:01:44,871 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5246/4
2025-11-06 10:01:44,872 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 3
2025-11-06 10:01:44,874 - INFO - ya.test - pytest_runtest_teardown: Test teardown
