2025-10-14 11:38:39,535 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-14 11:38:39,535 - INFO - ya.test - pytest_runtest_setup: test_create_and_drop_the_same_tenant2[enable_alter_database_create_hive_first--true]
2025-10-14 11:38:39,536 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-14 11:38:39,536 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-14 11:38:39,541 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-10-14 11:38:42,543 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:17283/1
2025-10-14 11:38:42,544 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22260
2025-10-14 11:38:42,544 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25820
2025-10-14 11:38:42,545 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20843
2025-10-14 11:38:42,545 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16541
2025-10-14 11:38:42,545 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3936
2025-10-14 11:38:42,545 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30632
2025-10-14 11:38:42,545 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2062
2025-10-14 11:38:42,546 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1296
2025-10-14 11:38:42,546 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8826
2025-10-14 11:38:42,546 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3394
2025-10-14 11:38:42,546 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19363
2025-10-14 11:38:42,547 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7663
2025-10-14 11:38:42,547 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3358
2025-10-14 11:38:42,547 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9791
2025-10-14 11:38:42,547 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19562
2025-10-14 11:38:42,547 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19567
2025-10-14 11:38:42,548 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22864
2025-10-14 11:38:42,548 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3552
2025-10-14 11:38:42,548 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21400
2025-10-14 11:38:42,548 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31412
2025-10-14 11:38:42,548 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29962
2025-10-14 11:38:42,549 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63595
2025-10-14 11:38:42,551 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12312
2025-10-14 11:38:42,551 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17283
2025-10-14 11:38:42,551 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25488
2025-10-14 11:38:42,625 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:38:42,626 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3172
2025-10-14 11:38:42,627 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-10-14 11:38:42,627 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-10-14 11:38:42,628 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/us16/0054b2/r3tmp/kikimr_cluster_cdzofou3
2025-10-14 11:38:42,679 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:38:42,680 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18461
2025-10-14 11:38:42,680 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23249
2025-10-14 11:38:42,681 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9636
2025-10-14 11:38:42,681 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30364
2025-10-14 11:38:42,682 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:42,683 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/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/us16/0054b2/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_s1gyr4qh.log --grpc-port=18461 --mon-port=23249 --ic-port=3172
2025-10-14 11:38:42,683 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18461/1, disk_size 68719476736
2025-10-14 11:38:42,683 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:42,683 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/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/us16/0054b2/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_s1gyr4qh.log --grpc-port=18461 --mon-port=23249 --ic-port=3172
2025-10-14 11:38:42,684 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/us16/0054b2/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/us16/0054b2/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_s1gyr4qh.log', '--grpc-port=18461', '--mon-port=23249', '--ic-port=3172')' in '/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:42,708 - DEBUG - ya.test - execute: Command pid: 2235483
2025-10-14 11:38:42,709 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18461/1
2025-10-14 11:38:42,710 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.37
2025-10-14 11:38:42,711 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.37
2025-10-14 11:38:42,711 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-14 11:38:42,712 - DEBUG - ya.test - execute: Command pid: 2235492
2025-10-14 11:38:44,911 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) rc: 0
2025-10-14 11:38:44,911 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) elapsed time (sec): 2.1978209018707275
2025-10-14 11:38:44,911 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) idrss: 0
2025-10-14 11:38:44,912 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) inblock: 0
2025-10-14 11:38:44,912 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) isrss: 0
2025-10-14 11:38:44,912 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) ixrss: 0
2025-10-14 11:38:44,912 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) majflt: 0
2025-10-14 11:38:44,912 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) maxrss: 542696
2025-10-14 11:38:44,912 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) minflt: 16219
2025-10-14 11:38:44,912 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) msgrcv: 0
2025-10-14 11:38:44,913 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) msgsnd: 0
2025-10-14 11:38:44,913 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) nivcsw: 322
2025-10-14 11:38:44,913 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) nsignals: 0
2025-10-14 11:38:44,913 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) nswap: 0
2025-10-14 11:38:44,913 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) nvcsw: 39
2025-10-14 11:38:44,913 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) oublock: 8
2025-10-14 11:38:44,913 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) stime: 1.374125
2025-10-14 11:38:44,914 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) utime: 0.135457
2025-10-14 11:38:44,914 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) wtime: 2.199
2025-10-14 11:38:44,914 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) output:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-t7yexzjxoe
    Host information: 
        Linux ghrun-t7yexzjxoe 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-10-14 11:38:44,914 - DEBUG - ya.test - _save_outputs: Command (pid 2235492) errors:

2025-10-14 11:38:44,923 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa
2025-10-14 11:38:47,274 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:38:47,275 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18461', '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: 3172\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-14 11:38:47,276 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.38
2025-10-14 11:38:47,277 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.38
2025-10-14 11:38:47,278 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18461', '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: 3172\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-14 11:38:47,285 - DEBUG - ya.test - execute: Command pid: 2238122
2025-10-14 11:38:48,301 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) rc: 0
2025-10-14 11:38:48,301 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) elapsed time (sec): 1.0112035274505615
2025-10-14 11:38:48,301 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) idrss: 0
2025-10-14 11:38:48,301 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) inblock: 0
2025-10-14 11:38:48,302 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) isrss: 0
2025-10-14 11:38:48,302 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) ixrss: 0
2025-10-14 11:38:48,302 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) majflt: 1
2025-10-14 11:38:48,302 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) maxrss: 550864
2025-10-14 11:38:48,302 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) minflt: 18355
2025-10-14 11:38:48,302 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) msgrcv: 0
2025-10-14 11:38:48,303 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) msgsnd: 0
2025-10-14 11:38:48,303 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) nivcsw: 144
2025-10-14 11:38:48,303 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) nsignals: 0
2025-10-14 11:38:48,303 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) nswap: 0
2025-10-14 11:38:48,303 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) nvcsw: 118
2025-10-14 11:38:48,303 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) oublock: 8
2025-10-14 11:38:48,303 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) stime: 0.59507
2025-10-14 11:38:48,304 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) utime: 0.144111
2025-10-14 11:38:48,304 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) wtime: 1.021
2025-10-14 11:38:48,304 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-14 11:38:48,304 - DEBUG - ya.test - _save_outputs: Command (pid 2238122) errors:

2025-10-14 11:38:48,305 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:38:48,305 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18461', '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-10-14 11:38:48,307 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.39
2025-10-14 11:38:48,308 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.39
2025-10-14 11:38:48,309 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:18461', '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-10-14 11:38:48,310 - DEBUG - ya.test - execute: Command pid: 2238709
2025-10-14 11:38:49,456 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) rc: 0
2025-10-14 11:38:49,457 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) elapsed time (sec): 1.1458532810211182
2025-10-14 11:38:49,467 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) idrss: 0
2025-10-14 11:38:49,467 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) inblock: 0
2025-10-14 11:38:49,468 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) isrss: 0
2025-10-14 11:38:49,468 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) ixrss: 0
2025-10-14 11:38:49,468 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) majflt: 0
2025-10-14 11:38:49,468 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) maxrss: 550836
2025-10-14 11:38:49,473 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) minflt: 18354
2025-10-14 11:38:49,475 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) msgrcv: 0
2025-10-14 11:38:49,475 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) msgsnd: 0
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) nivcsw: 168
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) nsignals: 0
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) nswap: 0
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) nvcsw: 161
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) oublock: 8
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) stime: 0.643986
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) utime: 0.123191
2025-10-14 11:38:49,476 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) wtime: 1.147
2025-10-14 11:38:49,477 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-14 11:38:49,477 - DEBUG - ya.test - _save_outputs: Command (pid 2238709) errors:

2025-10-14 11:38:49,477 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-14 11:38:49,505 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-14 11:38:49,524 - 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-10-14 11:38:49,525 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-10-14 11:38:50,046 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:38:50,047 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12064
2025-10-14 11:38:50,047 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9613
2025-10-14 11:38:50,048 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22023
2025-10-14 11:38:50,048 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18472
2025-10-14 11:38:50,048 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25806
2025-10-14 11:38:50,049 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:50,049 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_1onfr7yk.log --grpc-port=12064 --mon-port=9613 --ic-port=22023
2025-10-14 11:38:50,051 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:50,051 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_1onfr7yk.log --grpc-port=12064 --mon-port=9613 --ic-port=22023
2025-10-14 11:38:50,052 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/us16/0054b2/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:18461', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_1onfr7yk.log', '--grpc-port=12064', '--mon-port=9613', '--ic-port=22023')' in '/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:50,054 - DEBUG - ya.test - execute: Command pid: 2239492
2025-10-14 11:38:50,054 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:12064/1
2025-10-14 11:38:50,056 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.40
2025-10-14 11:38:50,058 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.40
2025-10-14 11:38:50,059 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-14 11:38:50,069 - DEBUG - ya.test - execute: Command pid: 2239495
2025-10-14 11:38:51,120 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) rc: 0
2025-10-14 11:38:51,120 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) elapsed time (sec): 1.0499083995819092
2025-10-14 11:38:51,120 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) idrss: 0
2025-10-14 11:38:51,121 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) inblock: 0
2025-10-14 11:38:51,121 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) isrss: 0
2025-10-14 11:38:51,121 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) ixrss: 0
2025-10-14 11:38:51,121 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) majflt: 0
2025-10-14 11:38:51,121 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) maxrss: 542696
2025-10-14 11:38:51,121 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) minflt: 16211
2025-10-14 11:38:51,122 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) msgrcv: 0
2025-10-14 11:38:51,122 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) msgsnd: 0
2025-10-14 11:38:51,122 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) nivcsw: 196
2025-10-14 11:38:51,122 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) nsignals: 0
2025-10-14 11:38:51,122 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) nswap: 0
2025-10-14 11:38:51,123 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) nvcsw: 22
2025-10-14 11:38:51,123 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) oublock: 8
2025-10-14 11:38:51,123 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) stime: 0.6625599999999999
2025-10-14 11:38:51,123 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) utime: 0.122004
2025-10-14 11:38:51,123 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) wtime: 1.061
2025-10-14 11:38:51,123 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) output:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-t7yexzjxoe
    Host information: 
        Linux ghrun-t7yexzjxoe 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-10-14 11:38:51,124 - DEBUG - ya.test - _save_outputs: Command (pid 2239495) errors:

2025-10-14 11:38:51,124 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa
2025-10-14 11:38:51,125 - 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-10-14 11:38:51,127 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 11:38:51,127 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-14 11:38:51,132 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-10-14 11:38:51,133 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-14 11:38:51,133 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f8e8b911280 state=pending>
2025-10-14 11:38:51,133 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-14 11:38:51,133 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,134 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,134 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-14 11:38:51,139 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-10-14 11:38:51,140 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c2ee2af8-fd80-42cf-b0ba-9060524c0439, localhost:18461): creating call state
2025-10-14 11:38:51,140 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c2ee2af8-fd80-42cf-b0ba-9060524c0439, localhost:18461): request = { database: "/Root/users/database" }
2025-10-14 11:38:51,160 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c2ee2af8-fd80-42cf-b0ba-9060524c0439, localhost:18461): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-t7yexzjxoe.auto.internal" port: 12064 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-10-14 11:38:51,161 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-t7yexzjxoe.auto.internal:12064, location , ssl: False>]>
2025-10-14 11:38:51,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-10-14 11:38:51,182 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:38:51,183 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,184 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 85232604-e810-4bd3-bbde-16087957662a, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,184 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 85232604-e810-4bd3-bbde-16087957662a, ghrun-t7yexzjxoe.auto.internal:12064): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:38:51,557 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 85232604-e810-4bd3-bbde-16087957662a, ghrun-t7yexzjxoe.auto.internal:12064): on response callback started
2025-10-14 11:38:51,558 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 85232604-e810-4bd3-bbde-16087957662a, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" } } } }
2025-10-14 11:38:51,559 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,560 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,561 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 85232604-e810-4bd3-bbde-16087957662a, ghrun-t7yexzjxoe.auto.internal:12064): on response callback success
2025-10-14 11:38:51,560 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, c7a6a3b3-ba9a-493f-8a53-bca67857e1e8, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,562 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, c7a6a3b3-ba9a-493f-8a53-bca67857e1e8, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" 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-10-14 11:38:51,592 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, c7a6a3b3-ba9a-493f-8a53-bca67857e1e8, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:38:51,593 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,644 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 4b773cde-0417-458f-b3fb-0b163215e0e9, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,644 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 4b773cde-0417-458f-b3fb-0b163215e0e9, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" 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-10-14 11:38:51,661 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 4b773cde-0417-458f-b3fb-0b163215e0e9, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:38:51,662 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,755 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, b290009b-8d9e-43d4-bb55-1caf7f23767c, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,756 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, b290009b-8d9e-43d4-bb55-1caf7f23767c, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" 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-10-14 11:38:51,821 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, b290009b-8d9e-43d4-bb55-1caf7f23767c, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:51,822 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,822 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-10-14 11:38:51,823 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, cb0414ff-3346-4f62-ae19-c92af9c90395, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,823 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, cb0414ff-3346-4f62-ae19-c92af9c90395, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" 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-10-14 11:38:51,871 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, cb0414ff-3346-4f62-ae19-c92af9c90395, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:51,871 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,872 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-10-14 11:38:51,872 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 310f65a2-b778-4285-813f-bc08f647027e, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,873 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 310f65a2-b778-4285-813f-bc08f647027e, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" 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-10-14 11:38:51,923 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 310f65a2-b778-4285-813f-bc08f647027e, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-14 11:38:51,923 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,924 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-10-14 11:38:51,924 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b725e1c1-85db-4ee3-98bb-3138af537117, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,925 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b725e1c1-85db-4ee3-98bb-3138af537117, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" 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-10-14 11:38:51,979 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b725e1c1-85db-4ee3-98bb-3138af537117, ghrun-t7yexzjxoe.auto.internal:12064): 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-10-14 11:38:51,980 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:51,981 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-10-14 11:38:51,981 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, b6858681-881d-4b87-9154-de660fe2899a, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:51,982 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, b6858681-881d-4b87-9154-de660fe2899a, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-14 11:38:52,013 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, b6858681-881d-4b87-9154-de660fe2899a, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:52,014 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:52,014 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-14 11:38:52,014 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-14 11:38:52,015 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-14 11:38:52,015 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f8e8b910ec0>, reason: session-pool-terminated
2025-10-14 11:38:52,016 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f8e8b910ec0> is no longer active. Current active count 0.
2025-10-14 11:38:52,016 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1d250287-d0d8-46f9-a80c-e8c214dda951, ghrun-t7yexzjxoe.auto.internal:12064): creating call state
2025-10-14 11:38:52,017 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1d250287-d0d8-46f9-a80c-e8c214dda951, ghrun-t7yexzjxoe.auto.internal:12064): request = { session_id: "ydb://session/3?node_id=50000&id=OWFkNjlkNzAtMTUxZGUxNDQtMTIxMDM4MjUtMWIyOWZhNzY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:38:52,015 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-14 11:38:52,018 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f8e8b910ec0>
2025-10-14 11:38:52,019 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-14 11:38:52,019 - DEBUG - ydb.topic - close: Close topic client
2025-10-14 11:38:52,020 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-14 11:38:52,020 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:38:52,019 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1d250287-d0d8-46f9-a80c-e8c214dda951, ghrun-t7yexzjxoe.auto.internal:12064): on response callback started
2025-10-14 11:38:52,020 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-t7yexzjxoe.auto.internal:12064
2025-10-14 11:38:52,021 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-14 11:38:52,022 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-10-14 11:38:52,021 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1d250287-d0d8-46f9-a80c-e8c214dda951, ghrun-t7yexzjxoe.auto.internal:12064): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:52,022 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-10-14 11:38:52,023 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1d250287-d0d8-46f9-a80c-e8c214dda951, ghrun-t7yexzjxoe.auto.internal:12064): on response callback success
2025-10-14 11:38:52,023 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-10-14 11:38:52,029 - 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=1760441932026988&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-14 11:38:52,035 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-10-14 11:38:52,539 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-10-14 11:38:52,540 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-10-14 11:38:52,543 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-10-14 11:38:55,546 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:12064/1
2025-10-14 11:38:55,546 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 0
2025-10-14 11:38:55,547 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-10-14 11:38:56,058 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:38:56,059 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20608
2025-10-14 11:38:56,060 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12906
2025-10-14 11:38:56,060 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3983
2025-10-14 11:38:56,061 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14690
2025-10-14 11:38:56,061 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6407
2025-10-14 11:38:56,062 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:56,062 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_lx3vh335.log --grpc-port=20608 --mon-port=12906 --ic-port=3983
2025-10-14 11:38:56,062 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:56,062 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_lx3vh335.log --grpc-port=20608 --mon-port=12906 --ic-port=3983
2025-10-14 11:38:56,063 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/us16/0054b2/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:18461', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_lx3vh335.log', '--grpc-port=20608', '--mon-port=12906', '--ic-port=3983')' in '/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:38:56,065 - DEBUG - ya.test - execute: Command pid: 2242579
2025-10-14 11:38:56,066 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20608/2
2025-10-14 11:38:56,067 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.41
2025-10-14 11:38:56,068 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.41
2025-10-14 11:38:56,069 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-14 11:38:56,070 - DEBUG - ya.test - execute: Command pid: 2242581
2025-10-14 11:38:56,651 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) rc: 0
2025-10-14 11:38:56,652 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) elapsed time (sec): 0.5808830261230469
2025-10-14 11:38:56,652 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) idrss: 0
2025-10-14 11:38:56,652 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) inblock: 0
2025-10-14 11:38:56,653 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) isrss: 0
2025-10-14 11:38:56,653 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) ixrss: 0
2025-10-14 11:38:56,653 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) majflt: 3
2025-10-14 11:38:56,654 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) maxrss: 542696
2025-10-14 11:38:56,654 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) minflt: 22050
2025-10-14 11:38:56,654 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) msgrcv: 0
2025-10-14 11:38:56,654 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) msgsnd: 0
2025-10-14 11:38:56,655 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) nivcsw: 32
2025-10-14 11:38:56,655 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) nsignals: 0
2025-10-14 11:38:56,655 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) nswap: 0
2025-10-14 11:38:56,655 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) nvcsw: 19
2025-10-14 11:38:56,655 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) oublock: 8
2025-10-14 11:38:56,656 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) stime: 0.421046
2025-10-14 11:38:56,656 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) utime: 0.107247
2025-10-14 11:38:56,656 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) wtime: 0.582
2025-10-14 11:38:56,656 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) output:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-t7yexzjxoe
    Host information: 
        Linux ghrun-t7yexzjxoe 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-10-14 11:38:56,656 - DEBUG - ya.test - _save_outputs: Command (pid 2242581) errors:

2025-10-14 11:38:56,657 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa
2025-10-14 11:38:56,657 - 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-10-14 11:38:56,660 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 11:38:56,660 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-10-14 11:38:56,661 - 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-10-14 11:38:56,661 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-14 11:38:56,665 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f8e8b822270 state=pending>
2025-10-14 11:38:56,666 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-14 11:38:56,666 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:56,667 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:56,667 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-14 11:38:56,671 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-10-14 11:38:56,675 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, fc127674-2428-43e7-87bf-7043d798efc1, localhost:18461): creating call state
2025-10-14 11:38:56,675 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, fc127674-2428-43e7-87bf-7043d798efc1, localhost:18461): request = { database: "/Root/users/database" }
2025-10-14 11:38:56,677 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, fc127674-2428-43e7-87bf-7043d798efc1, localhost:18461): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-10-14 11:38:56,678 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:18461". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-10-14 11:38:56,678 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:38:57,938 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 11:38:57,939 - 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-10-14 11:38:57,947 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-10-14 11:38:57,948 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, e6bd0860-2be0-4dda-b277-2554f8ea92d5, localhost:18461): creating call state
2025-10-14 11:38:57,948 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, e6bd0860-2be0-4dda-b277-2554f8ea92d5, localhost:18461): request = { database: "/Root/users/database" }
2025-10-14 11:38:57,950 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, e6bd0860-2be0-4dda-b277-2554f8ea92d5, localhost:18461): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-10-14 11:38:57,950 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/users/database. Endpoint: "localhost:18461". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-10-14 11:38:57,950 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:38:59,668 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,669 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-14 11:38:59,669 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 11:38:59,670 - 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-10-14 11:38:59,675 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-10-14 11:38:59,675 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 72cec2bc-e279-445c-b6d3-26302668517b, localhost:18461): creating call state
2025-10-14 11:38:59,676 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 72cec2bc-e279-445c-b6d3-26302668517b, localhost:18461): request = { database: "/Root/users/database" }
2025-10-14 11:38:59,681 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 72cec2bc-e279-445c-b6d3-26302668517b, localhost:18461): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-t7yexzjxoe.auto.internal" port: 20608 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-10-14 11:38:59,681 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-t7yexzjxoe.auto.internal:20608, location , ssl: False>]>
2025-10-14 11:38:59,682 - 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-10-14 11:38:59,689 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:38:59,690 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,691 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d1bb2259-93ac-43bd-85fc-aaff2e31774a, ghrun-t7yexzjxoe.auto.internal:20608): creating call state
2025-10-14 11:38:59,691 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d1bb2259-93ac-43bd-85fc-aaff2e31774a, ghrun-t7yexzjxoe.auto.internal:20608): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:38:59,694 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d1bb2259-93ac-43bd-85fc-aaff2e31774a, ghrun-t7yexzjxoe.auto.internal:20608): on response callback started
2025-10-14 11:38:59,697 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d1bb2259-93ac-43bd-85fc-aaff2e31774a, ghrun-t7yexzjxoe.auto.internal:20608): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=YWU0NzRjYTAtNGM4N2JjZS1iYWZhM2Q5Ni02NzZkNmQwNA==" } } } }
2025-10-14 11:38:59,698 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,698 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,699 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d1bb2259-93ac-43bd-85fc-aaff2e31774a, ghrun-t7yexzjxoe.auto.internal:20608): on response callback success
2025-10-14 11:38:59,699 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 0b7c48a5-7cd3-49db-88d8-950b1a38f528, ghrun-t7yexzjxoe.auto.internal:20608): creating call state
2025-10-14 11:38:59,700 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 0b7c48a5-7cd3-49db-88d8-950b1a38f528, ghrun-t7yexzjxoe.auto.internal:20608): request = { session_id: "ydb://session/3?node_id=50001&id=YWU0NzRjYTAtNGM4N2JjZS1iYWZhM2Q5Ni02NzZkNmQwNA==" 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-10-14 11:38:59,771 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 0b7c48a5-7cd3-49db-88d8-950b1a38f528, ghrun-t7yexzjxoe.auto.internal:20608): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:59,771 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,773 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-10-14 11:38:59,773 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 0349a6aa-b034-47dc-a906-561cc1c15722, ghrun-t7yexzjxoe.auto.internal:20608): creating call state
2025-10-14 11:38:59,774 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 0349a6aa-b034-47dc-a906-561cc1c15722, ghrun-t7yexzjxoe.auto.internal:20608): request = { session_id: "ydb://session/3?node_id=50001&id=YWU0NzRjYTAtNGM4N2JjZS1iYWZhM2Q5Ni02NzZkNmQwNA==" 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-10-14 11:38:59,812 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 0349a6aa-b034-47dc-a906-561cc1c15722, ghrun-t7yexzjxoe.auto.internal:20608): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:59,813 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,813 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-10-14 11:38:59,814 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 07a1b31e-abf4-48dd-832d-0053dca54736, ghrun-t7yexzjxoe.auto.internal:20608): creating call state
2025-10-14 11:38:59,814 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 07a1b31e-abf4-48dd-832d-0053dca54736, ghrun-t7yexzjxoe.auto.internal:20608): request = { session_id: "ydb://session/3?node_id=50001&id=YWU0NzRjYTAtNGM4N2JjZS1iYWZhM2Q5Ni02NzZkNmQwNA==" 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-10-14 11:38:59,843 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 07a1b31e-abf4-48dd-832d-0053dca54736, ghrun-t7yexzjxoe.auto.internal:20608): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-14 11:38:59,844 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,844 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-10-14 11:38:59,845 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 8d278b51-92b2-41e7-b185-582cfb3df71b, ghrun-t7yexzjxoe.auto.internal:20608): creating call state
2025-10-14 11:38:59,845 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 8d278b51-92b2-41e7-b185-582cfb3df71b, ghrun-t7yexzjxoe.auto.internal:20608): request = { session_id: "ydb://session/3?node_id=50001&id=YWU0NzRjYTAtNGM4N2JjZS1iYWZhM2Q5Ni02NzZkNmQwNA==" 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-10-14 11:38:59,891 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 8d278b51-92b2-41e7-b185-582cfb3df71b, ghrun-t7yexzjxoe.auto.internal:20608): 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-10-14 11:38:59,892 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,893 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-10-14 11:38:59,893 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 85fb155d-4917-4ce5-ac60-a357d6e6d2b4, ghrun-t7yexzjxoe.auto.internal:20608): creating call state
2025-10-14 11:38:59,894 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 85fb155d-4917-4ce5-ac60-a357d6e6d2b4, ghrun-t7yexzjxoe.auto.internal:20608): request = { session_id: "ydb://session/3?node_id=50001&id=YWU0NzRjYTAtNGM4N2JjZS1iYWZhM2Q5Ni02NzZkNmQwNA==" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-14 11:38:59,913 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 85fb155d-4917-4ce5-ac60-a357d6e6d2b4, ghrun-t7yexzjxoe.auto.internal:20608): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:59,914 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,914 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-14 11:38:59,914 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-14 11:38:59,914 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-14 11:38:59,915 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-14 11:38:59,915 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f8e89a2e590>, reason: session-pool-terminated
2025-10-14 11:38:59,915 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f8e89a2e590> is no longer active. Current active count 0.
2025-10-14 11:38:59,916 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 8ad3afee-a482-4f91-9c18-d9095d055119, ghrun-t7yexzjxoe.auto.internal:20608): creating call state
2025-10-14 11:38:59,916 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 8ad3afee-a482-4f91-9c18-d9095d055119, ghrun-t7yexzjxoe.auto.internal:20608): request = { session_id: "ydb://session/3?node_id=50001&id=YWU0NzRjYTAtNGM4N2JjZS1iYWZhM2Q5Ni02NzZkNmQwNA==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:38:59,917 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f8e89a2e590>
2025-10-14 11:38:59,918 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-14 11:38:59,918 - DEBUG - ydb.topic - close: Close topic client
2025-10-14 11:38:59,918 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-14 11:38:59,918 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:38:59,919 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-t7yexzjxoe.auto.internal:20608
2025-10-14 11:38:59,919 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8ad3afee-a482-4f91-9c18-d9095d055119, ghrun-t7yexzjxoe.auto.internal:20608): on response callback started
2025-10-14 11:38:59,920 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-14 11:38:59,920 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 8ad3afee-a482-4f91-9c18-d9095d055119, ghrun-t7yexzjxoe.auto.internal:20608): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:38:59,921 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-10-14 11:38:59,922 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-10-14 11:38:59,922 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-10-14 11:38:59,921 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8ad3afee-a482-4f91-9c18-d9095d055119, ghrun-t7yexzjxoe.auto.internal:20608): on response callback success
2025-10-14 11:38:59,927 - 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=1760441939924820&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-14 11:38:59,927 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-10-14 11:39:00,434 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-10-14 11:39:00,435 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-10-14 11:39:00,438 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-10-14 11:39:03,439 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:20608/2
2025-10-14 11:39:03,440 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 1
2025-10-14 11:39:03,440 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-10-14 11:39:03,958 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:39:03,959 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5223
2025-10-14 11:39:03,959 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31959
2025-10-14 11:39:03,963 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20395
2025-10-14 11:39:03,964 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62289
2025-10-14 11:39:03,964 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62290
2025-10-14 11:39:03,965 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23075
2025-10-14 11:39:03,965 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:39:03,968 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_021c46he.log --grpc-port=5223 --mon-port=31959 --ic-port=20395
2025-10-14 11:39:03,968 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:39:03,975 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_021c46he.log --grpc-port=5223 --mon-port=31959 --ic-port=20395
2025-10-14 11:39:03,977 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/us16/0054b2/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:18461', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_021c46he.log', '--grpc-port=5223', '--mon-port=31959', '--ic-port=20395')' in '/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:39:03,979 - DEBUG - ya.test - execute: Command pid: 2247113
2025-10-14 11:39:03,980 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5223/3
2025-10-14 11:39:03,981 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.42
2025-10-14 11:39:03,982 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.42
2025-10-14 11:39:03,983 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-14 11:39:03,984 - DEBUG - ya.test - execute: Command pid: 2247119
2025-10-14 11:39:04,510 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) rc: 0
2025-10-14 11:39:04,511 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) elapsed time (sec): 0.5257923603057861
2025-10-14 11:39:04,511 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) idrss: 0
2025-10-14 11:39:04,511 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) inblock: 0
2025-10-14 11:39:04,511 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) isrss: 0
2025-10-14 11:39:04,511 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) ixrss: 0
2025-10-14 11:39:04,511 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) majflt: 0
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) maxrss: 542696
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) minflt: 19285
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) msgrcv: 0
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) msgsnd: 0
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) nivcsw: 57
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) nsignals: 0
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) nswap: 0
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) nvcsw: 21
2025-10-14 11:39:04,512 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) oublock: 8
2025-10-14 11:39:04,513 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) stime: 0.29897199999999996
2025-10-14 11:39:04,513 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) utime: 0.123623
2025-10-14 11:39:04,513 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) wtime: 0.527
2025-10-14 11:39:04,513 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) output:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-t7yexzjxoe
    Host information: 
        Linux ghrun-t7yexzjxoe 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-10-14 11:39:04,513 - DEBUG - ya.test - _save_outputs: Command (pid 2247119) errors:

2025-10-14 11:39:04,513 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 3 version:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa
2025-10-14 11:39:04,514 - 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-10-14 11:39:04,515 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 11:39:04,515 - 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-10-14 11:39:04,520 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-10-14 11:39:04,521 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-14 11:39:04,521 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f8e8c3c5f50 state=pending>
2025-10-14 11:39:04,521 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-14 11:39:04,522 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:04,522 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:04,522 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-14 11:39:04,522 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-10-14 11:39:04,523 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 060b6078-4b15-4681-92bc-5ef929951842, localhost:18461): creating call state
2025-10-14 11:39:04,523 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 060b6078-4b15-4681-92bc-5ef929951842, localhost:18461): request = { database: "/Root/users/database" }
2025-10-14 11:39:04,526 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 060b6078-4b15-4681-92bc-5ef929951842, localhost:18461): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-t7yexzjxoe.auto.internal" port: 5223 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-10-14 11:39:04,526 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-t7yexzjxoe.auto.internal:5223, location , ssl: False>]>
2025-10-14 11:39:04,527 - 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-10-14 11:39:04,539 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:39:04,540 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:04,547 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, fc25dc5a-d30b-4de6-96df-aa72832ecfdd, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:04,548 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, fc25dc5a-d30b-4de6-96df-aa72832ecfdd, ghrun-t7yexzjxoe.auto.internal:5223): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:39:05,410 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, fc25dc5a-d30b-4de6-96df-aa72832ecfdd, ghrun-t7yexzjxoe.auto.internal:5223): on response callback started
2025-10-14 11:39:05,411 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, fc25dc5a-d30b-4de6-96df-aa72832ecfdd, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" } } } }
2025-10-14 11:39:05,412 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:05,412 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:05,412 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, fc25dc5a-d30b-4de6-96df-aa72832ecfdd, ghrun-t7yexzjxoe.auto.internal:5223): on response callback success
2025-10-14 11:39:05,413 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 462e8cde-9f8a-44c7-87c2-52df43cba9c5, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:05,414 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 462e8cde-9f8a-44c7-87c2-52df43cba9c5, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:05,449 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 462e8cde-9f8a-44c7-87c2-52df43cba9c5, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:05,450 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:05,501 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 7346e718-79d6-401c-ab95-1bc8bf260ca3, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:05,502 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 7346e718-79d6-401c-ab95-1bc8bf260ca3, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:05,524 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 7346e718-79d6-401c-ab95-1bc8bf260ca3, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:05,525 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:05,625 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 1e56fb8e-ebd6-4dcb-af76-117076c68449, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:05,626 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 1e56fb8e-ebd6-4dcb-af76-117076c68449, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:05,635 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 1e56fb8e-ebd6-4dcb-af76-117076c68449, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:05,635 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:05,817 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, c81dc648-1aae-476c-b503-767b43488af2, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:05,818 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, c81dc648-1aae-476c-b503-767b43488af2, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:05,824 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, c81dc648-1aae-476c-b503-767b43488af2, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:05,825 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:06,197 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, fd6f46c9-6d7a-40c2-af66-e8a3b072c018, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:06,198 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, fd6f46c9-6d7a-40c2-af66-e8a3b072c018, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:06,216 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, fd6f46c9-6d7a-40c2-af66-e8a3b072c018, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:06,216 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:06,958 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 62ceb72c-0256-4090-bec7-d65dac199d78, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:06,965 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 62ceb72c-0256-4090-bec7-d65dac199d78, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:07,342 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 62ceb72c-0256-4090-bec7-d65dac199d78, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:07,343 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:07,343 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-10-14 11:39:07,344 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 99c4f78f-04be-4e7c-8b6d-d9edd6b1be22, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:07,344 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 99c4f78f-04be-4e7c-8b6d-d9edd6b1be22, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:07,391 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 99c4f78f-04be-4e7c-8b6d-d9edd6b1be22, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:07,392 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:07,392 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-10-14 11:39:07,393 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, f473bc03-aed2-4198-8a7e-c594c471d1a2, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:07,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, f473bc03-aed2-4198-8a7e-c594c471d1a2, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:07,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, f473bc03-aed2-4198-8a7e-c594c471d1a2, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-14 11:39:07,433 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:07,433 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-10-14 11:39:07,434 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b67e568d-75a7-466e-b7c2-41332ce001f8, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:07,435 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b67e568d-75a7-466e-b7c2-41332ce001f8, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" 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-10-14 11:39:07,473 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b67e568d-75a7-466e-b7c2-41332ce001f8, ghrun-t7yexzjxoe.auto.internal:5223): 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-10-14 11:39:07,474 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:07,475 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-10-14 11:39:07,475 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, e336d1d4-5a34-4d08-b2ef-fdb3f57653d7, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:07,476 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, e336d1d4-5a34-4d08-b2ef-fdb3f57653d7, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-14 11:39:07,493 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, e336d1d4-5a34-4d08-b2ef-fdb3f57653d7, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:07,493 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:07,493 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-14 11:39:07,494 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-14 11:39:07,494 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-14 11:39:07,494 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f8e8c3c63b0>, reason: session-pool-terminated
2025-10-14 11:39:07,494 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f8e8c3c63b0> is no longer active. Current active count 0.
2025-10-14 11:39:07,495 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1f7f825b-7f16-41cd-bc9f-4c7633eddc64, ghrun-t7yexzjxoe.auto.internal:5223): creating call state
2025-10-14 11:39:07,495 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1f7f825b-7f16-41cd-bc9f-4c7633eddc64, ghrun-t7yexzjxoe.auto.internal:5223): request = { session_id: "ydb://session/3?node_id=50002&id=ZjgzNGI3YTctMTFhM2ViY2YtMWIwM2ZiNWYtZGI3NjkzMTg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:39:07,496 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-14 11:39:07,497 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f8e8c3c63b0>
2025-10-14 11:39:07,498 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1f7f825b-7f16-41cd-bc9f-4c7633eddc64, ghrun-t7yexzjxoe.auto.internal:5223): on response callback started
2025-10-14 11:39:07,499 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1f7f825b-7f16-41cd-bc9f-4c7633eddc64, ghrun-t7yexzjxoe.auto.internal:5223): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:07,499 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1f7f825b-7f16-41cd-bc9f-4c7633eddc64, ghrun-t7yexzjxoe.auto.internal:5223): on response callback success
2025-10-14 11:39:07,498 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-14 11:39:07,500 - DEBUG - ydb.topic - close: Close topic client
2025-10-14 11:39:07,500 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-14 11:39:07,500 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:39:07,500 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-t7yexzjxoe.auto.internal:5223
2025-10-14 11:39:07,501 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-14 11:39:07,502 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-10-14 11:39:07,502 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-10-14 11:39:07,503 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-10-14 11:39:07,509 - 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=1760441947506365&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-14 11:39:07,509 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-10-14 11:39:08,013 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-10-14 11:39:08,014 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-10-14 11:39:08,017 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-10-14 11:39:11,018 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5223/3
2025-10-14 11:39:11,019 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 2
2025-10-14 11:39:11,019 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create_database
2025-10-14 11:39:11,532 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd
2025-10-14 11:39:11,533 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11581
2025-10-14 11:39:11,534 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3668
2025-10-14 11:39:11,534 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29446
2025-10-14 11:39:11,534 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6644
2025-10-14 11:39:11,535 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12987
2025-10-14 11:39:11,535 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:39:11,536 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_u8uxm2is.log --grpc-port=11581 --mon-port=3668 --ic-port=29446
2025-10-14 11:39:11,536 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:39:11,536 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18461 --tenant=/Root/users/database --log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_u8uxm2is.log --grpc-port=11581 --mon-port=3668 --ic-port=29446
2025-10-14 11:39:11,537 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/us16/0054b2/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:18461', '--tenant=/Root/users/database', '--log-file-name=/home/runner/.ya/build/build_root/us16/0054b2/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_u8uxm2is.log', '--grpc-port=11581', '--mon-port=3668', '--ic-port=29446')' in '/home/runner/.ya/build/build_root/us16/0054b2/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-10-14 11:39:11,539 - DEBUG - ya.test - execute: Command pid: 2252620
2025-10-14 11:39:11,540 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:11581/4
2025-10-14 11:39:11,541 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.43
2025-10-14 11:39:11,542 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.43
2025-10-14 11:39:11,542 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/us16/0054b2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-14 11:39:11,543 - DEBUG - ya.test - execute: Command pid: 2252624
2025-10-14 11:39:12,620 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) rc: 0
2025-10-14 11:39:12,620 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) elapsed time (sec): 1.0753660202026367
2025-10-14 11:39:12,620 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) idrss: 0
2025-10-14 11:39:12,620 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) inblock: 0
2025-10-14 11:39:12,621 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) isrss: 0
2025-10-14 11:39:12,621 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) ixrss: 0
2025-10-14 11:39:12,621 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) majflt: 0
2025-10-14 11:39:12,621 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) maxrss: 542696
2025-10-14 11:39:12,621 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) minflt: 16211
2025-10-14 11:39:12,621 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) msgrcv: 0
2025-10-14 11:39:12,622 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) msgsnd: 0
2025-10-14 11:39:12,622 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) nivcsw: 90
2025-10-14 11:39:12,622 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) nsignals: 0
2025-10-14 11:39:12,622 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) nswap: 0
2025-10-14 11:39:12,622 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) nvcsw: 21
2025-10-14 11:39:12,622 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) oublock: 8
2025-10-14 11:39:12,623 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) stime: 0.836356
2025-10-14 11:39:12,623 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) utime: 0.11845699999999999
2025-10-14 11:39:12,623 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) wtime: 1.077
2025-10-14 11:39:12,623 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) output:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-t7yexzjxoe
    Host information: 
        Linux ghrun-t7yexzjxoe 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-10-14 11:39:12,624 - DEBUG - ya.test - _save_outputs: Command (pid 2252624) errors:

2025-10-14 11:39:12,624 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 4 version:
Git info:
    Commit: 22c14a416532147a22ce472567eee29433a7bd3e
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 38221f8c97435dc51a4a0688693f6cef06de3eaa
2025-10-14 11:39:12,624 - 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-10-14 11:39:12,626 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 11:39:12,628 - 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-10-14 11:39:12,628 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table one
2025-10-14 11:39:12,632 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-14 11:39:12,632 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f8e8c1367c0 state=pending>
2025-10-14 11:39:12,633 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-14 11:39:12,634 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:12,634 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:12,635 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-14 11:39:12,636 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database
2025-10-14 11:39:12,637 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 04f783ca-9ee7-4e55-a519-f2fba99061b5, localhost:18461): creating call state
2025-10-14 11:39:12,637 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 04f783ca-9ee7-4e55-a519-f2fba99061b5, localhost:18461): request = { database: "/Root/users/database" }
2025-10-14 11:39:12,640 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 04f783ca-9ee7-4e55-a519-f2fba99061b5, localhost:18461): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-t7yexzjxoe.auto.internal" port: 11581 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-10-14 11:39:12,640 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-t7yexzjxoe.auto.internal:11581, location , ssl: False>]>
2025-10-14 11:39:12,641 - 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-10-14 11:39:12,648 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:39:12,648 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:12,649 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cca1ea71-ea18-4656-836e-be5e444956e3, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:12,650 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cca1ea71-ea18-4656-836e-be5e444956e3, ghrun-t7yexzjxoe.auto.internal:11581): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:39:12,652 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cca1ea71-ea18-4656-836e-be5e444956e3, ghrun-t7yexzjxoe.auto.internal:11581): on response callback started
2025-10-14 11:39:12,653 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cca1ea71-ea18-4656-836e-be5e444956e3, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" } } } }
2025-10-14 11:39:12,654 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:12,654 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:12,654 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cca1ea71-ea18-4656-836e-be5e444956e3, ghrun-t7yexzjxoe.auto.internal:11581): on response callback success
2025-10-14 11:39:12,655 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, f7764fcc-d053-4c13-86bb-488f0522485f, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:12,656 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, f7764fcc-d053-4c13-86bb-488f0522485f, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" 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-10-14 11:39:12,662 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, f7764fcc-d053-4c13-86bb-488f0522485f, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:12,663 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:12,713 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 55d8663b-4a0c-4258-8b1e-eaadbd054917, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:12,714 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 55d8663b-4a0c-4258-8b1e-eaadbd054917, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" 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-10-14 11:39:12,719 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 55d8663b-4a0c-4258-8b1e-eaadbd054917, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:12,720 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:12,815 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 3c81edc2-79aa-4548-ba4e-bd6f47e6aaf5, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:12,816 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 3c81edc2-79aa-4548-ba4e-bd6f47e6aaf5, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" 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-10-14 11:39:12,823 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 3c81edc2-79aa-4548-ba4e-bd6f47e6aaf5, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: UNAVAILABLE issues { message: "Schemeshard not available" severity: 1 } } }
2025-10-14 11:39:12,823 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:13,015 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 4d0320e0-fcef-4df3-b181-0b3682beda1c, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:13,016 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 4d0320e0-fcef-4df3-b181-0b3682beda1c, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" 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-10-14 11:39:13,053 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 4d0320e0-fcef-4df3-b181-0b3682beda1c, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:13,054 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:13,054 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: create table two
2025-10-14 11:39:13,054 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 1fefa087-31d2-4668-b3ec-365b09d874eb, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:13,055 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 1fefa087-31d2-4668-b3ec-365b09d874eb, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" 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-10-14 11:39:13,083 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 1fefa087-31d2-4668-b3ec-365b09d874eb, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:13,084 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:13,084 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: write_some_data
2025-10-14 11:39:13,084 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e08faa50-3841-4144-8e2f-8125b89e6043, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:13,085 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e08faa50-3841-4144-8e2f-8125b89e6043, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" 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-10-14 11:39:13,114 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e08faa50-3841-4144-8e2f-8125b89e6043, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-14 11:39:13,115 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:13,115 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: read_some_data
2025-10-14 11:39:13,116 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4f9a4285-1fea-42ef-b494-a1c05e4a688b, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:13,116 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4f9a4285-1fea-42ef-b494-a1c05e4a688b, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" 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-10-14 11:39:13,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4f9a4285-1fea-42ef-b494-a1c05e4a688b, ghrun-t7yexzjxoe.auto.internal:11581): 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-10-14 11:39:13,152 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:13,152 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: drop table two
2025-10-14 11:39:13,152 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 7a8e62e1-bb33-4eb3-9e3a-3c01f7ffcd63, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:13,153 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 7a8e62e1-bb33-4eb3-9e3a-3c01f7ffcd63, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" path: "/Root/users/database/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-14 11:39:13,171 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 7a8e62e1-bb33-4eb3-9e3a-3c01f7ffcd63, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:13,172 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:13,172 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-14 11:39:13,172 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-14 11:39:13,173 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-14 11:39:13,173 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-14 11:39:13,174 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f8e8c136c70>, reason: session-pool-terminated
2025-10-14 11:39:13,174 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f8e8c136c70> is no longer active. Current active count 0.
2025-10-14 11:39:13,174 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 4e7ae33d-dc0b-4a76-94a3-da1fc136407f, ghrun-t7yexzjxoe.auto.internal:11581): creating call state
2025-10-14 11:39:13,175 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 4e7ae33d-dc0b-4a76-94a3-da1fc136407f, ghrun-t7yexzjxoe.auto.internal:11581): request = { session_id: "ydb://session/3?node_id=50003&id=YzY2OTNhYTctODA1Njg3MTYtYWU1NWNkM2ItMzExM2Y3NGI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-14 11:39:13,176 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f8e8c136c70>
2025-10-14 11:39:13,176 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-14 11:39:13,177 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 4e7ae33d-dc0b-4a76-94a3-da1fc136407f, ghrun-t7yexzjxoe.auto.internal:11581): on response callback started
2025-10-14 11:39:13,177 - DEBUG - ydb.topic - close: Close topic client
2025-10-14 11:39:13,177 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 4e7ae33d-dc0b-4a76-94a3-da1fc136407f, ghrun-t7yexzjxoe.auto.internal:11581): response = { operation { ready: true status: SUCCESS } }
2025-10-14 11:39:13,178 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-14 11:39:13,178 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 4e7ae33d-dc0b-4a76-94a3-da1fc136407f, ghrun-t7yexzjxoe.auto.internal:11581): on response callback success
2025-10-14 11:39:13,179 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18461
2025-10-14 11:39:13,179 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-t7yexzjxoe.auto.internal:11581
2025-10-14 11:39:13,180 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-14 11:39:13,181 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: remove_database
2025-10-14 11:39:13,181 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database
2025-10-14 11:39:13,181 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database: send console operation, token None
2025-10-14 11:39:13,190 - 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=1760441953184086&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-14 11:39:13,191 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: wait console operation done
2025-10-14 11:39:13,694 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database: console operation done
2025-10-14 11:39:13,695 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: wait tenant gone
2025-10-14 11:39:13,697 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database: tenant gone
2025-10-14 11:39:16,698 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11581/4
2025-10-14 11:39:16,699 - DEBUG - __tests__.test_dynamic_tenants - test_create_and_drop_the_same_tenant2: done 3
2025-10-14 11:39:16,701 - INFO - ya.test - pytest_runtest_teardown: Test teardown
