2025-11-01 16:44:30,100 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:44:30,106 - INFO - ya.test - pytest_runtest_setup: test_create_and_drop_tenants[enable_alter_database_create_hive_first--true]
2025-11-01 16:44:30,107 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:44:30,123 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:44:30,135 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-11-01 16:44:37,143 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:8274/1
2025-11-01 16:44:37,187 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6607
2025-11-01 16:44:37,220 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27762
2025-11-01 16:44:37,227 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32354
2025-11-01 16:44:37,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22839
2025-11-01 16:44:37,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7103
2025-11-01 16:44:37,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26719
2025-11-01 16:44:37,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8118
2025-11-01 16:44:37,228 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4473
2025-11-01 16:44:37,229 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8947
2025-11-01 16:44:37,244 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25781
2025-11-01 16:44:37,253 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13077
2025-11-01 16:44:37,259 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11550
2025-11-01 16:44:37,259 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21093
2025-11-01 16:44:37,259 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11718
2025-11-01 16:44:37,260 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32569
2025-11-01 16:44:37,260 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21930
2025-11-01 16:44:37,267 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18250
2025-11-01 16:44:37,268 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30899
2025-11-01 16:44:37,279 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17233
2025-11-01 16:44:37,279 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27514
2025-11-01 16:44:37,279 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8170
2025-11-01 16:44:37,279 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63194
2025-11-01 16:44:37,279 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8264
2025-11-01 16:44:37,280 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20060
2025-11-01 16:44:37,280 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29955
2025-11-01 16:44:37,280 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26969
2025-11-01 16:44:37,280 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10161
2025-11-01 16:44:37,281 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31170
2025-11-01 16:44:37,281 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15852
2025-11-01 16:44:37,281 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7406
2025-11-01 16:44:37,282 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24494
2025-11-01 16:44:37,283 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11901
2025-11-01 16:44:37,288 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6163
2025-11-01 16:44:37,289 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25558
2025-11-01 16:44:37,291 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28217
2025-11-01 16:44:37,301 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13699
2025-11-01 16:44:37,311 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7455
2025-11-01 16:44:37,312 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21876
2025-11-01 16:44:37,312 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4201
2025-11-01 16:44:37,312 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21205
2025-11-01 16:44:37,312 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11644
2025-11-01 16:44:37,312 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24279
2025-11-01 16:44:37,313 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25144
2025-11-01 16:44:37,313 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13397
2025-11-01 16:44:37,313 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/65201
2025-11-01 16:44:37,313 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24280
2025-11-01 16:44:37,313 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13867
2025-11-01 16:44:37,314 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20100
2025-11-01 16:44:37,314 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8274
2025-11-01 16:44:37,363 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9450
2025-11-01 16:44:37,421 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:44:37,423 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63108
2025-11-01 16:44:37,454 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-11-01 16:44:37,457 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-11-01 16:44:37,496 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/q6a6/0023f4/r3tmp/kikimr_cluster__ohfxtqf
2025-11-01 16:44:37,551 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:44:37,551 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/65199
2025-11-01 16:44:37,559 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62150
2025-11-01 16:44:37,560 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27012
2025-11-01 16:44:37,561 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19918
2025-11-01 16:44:37,600 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:44:37,610 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/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/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_pehp6l2_.log --grpc-port=65199 --mon-port=62150 --ic-port=63108
2025-11-01 16:44:37,654 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:65199/1, disk_size 68719476736
2025-11-01 16:44:37,663 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:44:37,667 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/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/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_pehp6l2_.log --grpc-port=65199 --mon-port=62150 --ic-port=63108
2025-11-01 16:44:37,674 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_pehp6l2_.log', '--grpc-port=65199', '--mon-port=62150', '--ic-port=63108')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-11-01 16:44:37,677 - DEBUG - ya.test - execute: Command pid: 3004166
2025-11-01 16:44:37,721 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:65199/1
2025-11-01 16:44:37,722 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.16
2025-11-01 16:44:37,727 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.16
2025-11-01 16:44:37,728 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:44:37,733 - DEBUG - ya.test - execute: Command pid: 3004225
2025-11-01 16:44:38,608 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) rc: 0
2025-11-01 16:44:38,608 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) elapsed time (sec): 0.8703463077545166
2025-11-01 16:44:38,609 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) idrss: 0
2025-11-01 16:44:38,610 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) inblock: 0
2025-11-01 16:44:38,610 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) isrss: 0
2025-11-01 16:44:38,611 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) ixrss: 0
2025-11-01 16:44:38,615 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) majflt: 0
2025-11-01 16:44:38,616 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) maxrss: 538628
2025-11-01 16:44:38,616 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) minflt: 16152
2025-11-01 16:44:38,618 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) msgrcv: 0
2025-11-01 16:44:38,620 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) msgsnd: 0
2025-11-01 16:44:38,635 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) nivcsw: 170
2025-11-01 16:44:38,640 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) nsignals: 0
2025-11-01 16:44:38,640 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) nswap: 0
2025-11-01 16:44:38,640 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) nvcsw: 18
2025-11-01 16:44:38,640 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) oublock: 8
2025-11-01 16:44:38,641 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) stime: 0.548516
2025-11-01 16:44:38,641 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) utime: 0.116575
2025-11-01 16:44:38,641 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) wtime: 0.875
2025-11-01 16:44:38,641 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:44:38,642 - DEBUG - ya.test - _save_outputs: Command (pid 3004225) errors:

2025-11-01 16:44:38,642 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:44:41,261 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:44:41,292 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65199', '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: 63108\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:44:41,315 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.17
2025-11-01 16:44:41,328 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.17
2025-11-01 16:44:41,328 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65199', '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: 63108\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:44:41,329 - DEBUG - ya.test - execute: Command pid: 3007102
2025-11-01 16:44:43,115 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) rc: 0
2025-11-01 16:44:43,115 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) elapsed time (sec): 1.784602403640747
2025-11-01 16:44:43,115 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) idrss: 0
2025-11-01 16:44:43,116 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) inblock: 0
2025-11-01 16:44:43,116 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) isrss: 0
2025-11-01 16:44:43,116 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) ixrss: 0
2025-11-01 16:44:43,116 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) majflt: 1
2025-11-01 16:44:43,116 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) maxrss: 547028
2025-11-01 16:44:43,116 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) minflt: 18318
2025-11-01 16:44:43,116 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) msgrcv: 0
2025-11-01 16:44:43,117 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) msgsnd: 0
2025-11-01 16:44:43,117 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) nivcsw: 378
2025-11-01 16:44:43,117 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) nsignals: 0
2025-11-01 16:44:43,117 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) nswap: 0
2025-11-01 16:44:43,117 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) nvcsw: 144
2025-11-01 16:44:43,117 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) oublock: 8
2025-11-01 16:44:43,118 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) stime: 1.135997
2025-11-01 16:44:43,118 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) utime: 0.108791
2025-11-01 16:44:43,118 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) wtime: 1.786
2025-11-01 16:44:43,118 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 16:44:43,118 - DEBUG - ya.test - _save_outputs: Command (pid 3007102) errors:

2025-11-01 16:44:43,119 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:44:43,119 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65199', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-01 16:44:43,120 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.18
2025-11-01 16:44:43,121 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.18
2025-11-01 16:44:43,121 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:65199', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:44:43,130 - DEBUG - ya.test - execute: Command pid: 3008592
2025-11-01 16:44:45,960 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) rc: 0
2025-11-01 16:44:45,963 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) elapsed time (sec): 2.8222110271453857
2025-11-01 16:44:45,992 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) idrss: 0
2025-11-01 16:44:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) inblock: 0
2025-11-01 16:44:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) isrss: 0
2025-11-01 16:44:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) ixrss: 0
2025-11-01 16:44:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) majflt: 0
2025-11-01 16:44:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) maxrss: 546952
2025-11-01 16:44:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) minflt: 18273
2025-11-01 16:44:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) msgrcv: 0
2025-11-01 16:44:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) msgsnd: 0
2025-11-01 16:44:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) nivcsw: 399
2025-11-01 16:44:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) nsignals: 0
2025-11-01 16:44:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) nswap: 0
2025-11-01 16:44:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) nvcsw: 103
2025-11-01 16:44:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) oublock: 8
2025-11-01 16:44:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) stime: 1.288224
2025-11-01 16:44:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) utime: 0.104797
2025-11-01 16:44:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) wtime: 2.832
2025-11-01 16:44:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:44:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 3008592) errors:

2025-11-01 16:44:46,002 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:44:46,032 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:44:46,086 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_dynamic_tenants.py, test_name: test_create_and_drop_tenants[enable_alter_database_create_hive_first--true])
2025-11-01 16:44:46,625 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:44:46,625 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29022
2025-11-01 16:44:46,625 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26595
2025-11-01 16:44:46,626 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14268
2025-11-01 16:44:46,626 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17036
2025-11-01 16:44:46,658 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8664
2025-11-01 16:44:46,658 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:44:46,658 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_0 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_k86i7n34.log --grpc-port=29022 --mon-port=26595 --ic-port=14268
2025-11-01 16:44:46,663 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:44:46,663 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_0 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_k86i7n34.log --grpc-port=29022 --mon-port=26595 --ic-port=14268
2025-11-01 16:44:46,665 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_0', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_k86i7n34.log', '--grpc-port=29022', '--mon-port=26595', '--ic-port=14268')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-11-01 16:44:46,678 - DEBUG - ya.test - execute: Command pid: 3011308
2025-11-01 16:44:46,680 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:29022/1
2025-11-01 16:44:46,699 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.19
2025-11-01 16:44:46,703 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.19
2025-11-01 16:44:46,731 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:44:46,734 - DEBUG - ya.test - execute: Command pid: 3011329
2025-11-01 16:44:50,807 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) rc: 0
2025-11-01 16:44:50,807 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) elapsed time (sec): 4.02016544342041
2025-11-01 16:44:50,808 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) idrss: 0
2025-11-01 16:44:50,808 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) inblock: 0
2025-11-01 16:44:50,827 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) isrss: 0
2025-11-01 16:44:50,827 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) ixrss: 0
2025-11-01 16:44:50,828 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) majflt: 0
2025-11-01 16:44:50,828 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) maxrss: 538512
2025-11-01 16:44:50,828 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) minflt: 16136
2025-11-01 16:44:50,836 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) msgrcv: 0
2025-11-01 16:44:50,836 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) msgsnd: 0
2025-11-01 16:44:50,876 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) nivcsw: 601
2025-11-01 16:44:50,876 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) nsignals: 0
2025-11-01 16:44:50,876 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) nswap: 0
2025-11-01 16:44:50,945 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) nvcsw: 52
2025-11-01 16:44:50,978 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) oublock: 8
2025-11-01 16:44:50,978 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) stime: 2.129196
2025-11-01 16:44:50,978 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) utime: 0.089922
2025-11-01 16:44:50,981 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) wtime: 4.028
2025-11-01 16:44:51,047 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:44:51,047 - DEBUG - ya.test - _save_outputs: Command (pid 3011329) errors:

2025-11-01 16:44:51,047 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:44:51,060 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:44:51,062 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:44:51,063 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:44:51,068 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:44:51,095 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5efafb300 state=pending>
2025-11-01 16:44:51,115 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:44:51,115 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,115 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,139 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:44:51,075 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_0
2025-11-01 16:44:51,174 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 2db92597-c65a-47cd-ab9d-31ea6a65f9d9, localhost:65199): creating call state
2025-11-01 16:44:51,199 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 2db92597-c65a-47cd-ab9d-31ea6a65f9d9, localhost:65199): request = { database: "/Root/users/database_0" }
2025-11-01 16:44:51,208 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 2db92597-c65a-47cd-ab9d-31ea6a65f9d9, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 29022 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-11-01 16:44:51,243 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_0: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:29022, location , ssl: False>]>
2025-11-01 16:44:51,247 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:44:51,256 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:44:51,257 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,286 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4481f64e-2da9-4e45-8934-a0225d9f04fe, ghrun-tvlw7frnpa.auto.internal:29022): creating call state
2025-11-01 16:44:51,332 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4481f64e-2da9-4e45-8934-a0225d9f04fe, ghrun-tvlw7frnpa.auto.internal:29022): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:44:51,336 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4481f64e-2da9-4e45-8934-a0225d9f04fe, ghrun-tvlw7frnpa.auto.internal:29022): on response callback started
2025-11-01 16:44:51,422 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4481f64e-2da9-4e45-8934-a0225d9f04fe, ghrun-tvlw7frnpa.auto.internal:29022): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NTA2MTA2ZWYtNjRkMjg0YjgtYjJlNGFjODctOTU0MDA4Zjg=" } } } }
2025-11-01 16:44:51,422 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,423 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,427 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, abc6b368-cf4f-48a8-b16f-913ad3ffe8b5, ghrun-tvlw7frnpa.auto.internal:29022): creating call state
2025-11-01 16:44:51,429 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4481f64e-2da9-4e45-8934-a0225d9f04fe, ghrun-tvlw7frnpa.auto.internal:29022): on response callback success
2025-11-01 16:44:51,431 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, abc6b368-cf4f-48a8-b16f-913ad3ffe8b5, ghrun-tvlw7frnpa.auto.internal:29022): request = { session_id: "ydb://session/3?node_id=50000&id=NTA2MTA2ZWYtNjRkMjg0YjgtYjJlNGFjODctOTU0MDA4Zjg=" path: "/Root/users/database_0/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:44:51,647 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, abc6b368-cf4f-48a8-b16f-913ad3ffe8b5, ghrun-tvlw7frnpa.auto.internal:29022): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:44:51,695 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,703 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 89b78e5f-4179-4d94-915c-761c74acacd0, ghrun-tvlw7frnpa.auto.internal:29022): creating call state
2025-11-01 16:44:51,731 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 89b78e5f-4179-4d94-915c-761c74acacd0, ghrun-tvlw7frnpa.auto.internal:29022): request = { session_id: "ydb://session/3?node_id=50000&id=NTA2MTA2ZWYtNjRkMjg0YjgtYjJlNGFjODctOTU0MDA4Zjg=" path: "/Root/users/database_0/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:44:51,853 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 89b78e5f-4179-4d94-915c-761c74acacd0, ghrun-tvlw7frnpa.auto.internal:29022): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:44:51,853 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,854 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 21b27cec-3425-48e6-9666-b82851947a2e, ghrun-tvlw7frnpa.auto.internal:29022): creating call state
2025-11-01 16:44:51,879 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 21b27cec-3425-48e6-9666-b82851947a2e, ghrun-tvlw7frnpa.auto.internal:29022): request = { session_id: "ydb://session/3?node_id=50000&id=NTA2MTA2ZWYtNjRkMjg0YjgtYjJlNGFjODctOTU0MDA4Zjg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_0\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_0\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:44:51,947 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 21b27cec-3425-48e6-9666-b82851947a2e, ghrun-tvlw7frnpa.auto.internal:29022): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:44:51,956 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:51,986 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, d10589d1-5136-427d-be3e-692ed9664827, ghrun-tvlw7frnpa.auto.internal:29022): creating call state
2025-11-01 16:44:51,986 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, d10589d1-5136-427d-be3e-692ed9664827, ghrun-tvlw7frnpa.auto.internal:29022): request = { session_id: "ydb://session/3?node_id=50000&id=NTA2MTA2ZWYtNjRkMjg0YjgtYjJlNGFjODctOTU0MDA4Zjg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:44:52,046 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, d10589d1-5136-427d-be3e-692ed9664827, ghrun-tvlw7frnpa.auto.internal:29022): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:44:52,047 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:52,055 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 26d99f40-d037-4484-8d17-ad23c39b583e, ghrun-tvlw7frnpa.auto.internal:29022): creating call state
2025-11-01 16:44:52,055 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 26d99f40-d037-4484-8d17-ad23c39b583e, ghrun-tvlw7frnpa.auto.internal:29022): request = { session_id: "ydb://session/3?node_id=50000&id=NTA2MTA2ZWYtNjRkMjg0YjgtYjJlNGFjODctOTU0MDA4Zjg=" path: "/Root/users/database_0/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:44:52,097 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 26d99f40-d037-4484-8d17-ad23c39b583e, ghrun-tvlw7frnpa.auto.internal:29022): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:44:52,109 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:52,109 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:44:52,109 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:44:52,112 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:44:52,113 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5efafb3f0>, reason: session-pool-terminated
2025-11-01 16:44:52,110 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:44:52,113 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5efafb3f0> is no longer active. Current active count 0.
2025-11-01 16:44:52,116 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 65f65905-7d08-4f5f-82be-81e120013b09, ghrun-tvlw7frnpa.auto.internal:29022): creating call state
2025-11-01 16:44:52,128 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 65f65905-7d08-4f5f-82be-81e120013b09, ghrun-tvlw7frnpa.auto.internal:29022): request = { session_id: "ydb://session/3?node_id=50000&id=NTA2MTA2ZWYtNjRkMjg0YjgtYjJlNGFjODctOTU0MDA4Zjg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:44:52,130 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5efafb3f0>
2025-11-01 16:44:52,130 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:44:52,130 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:44:52,131 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:44:52,131 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:44:52,131 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:29022
2025-11-01 16:44:52,131 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:44:52,133 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 65f65905-7d08-4f5f-82be-81e120013b09, ghrun-tvlw7frnpa.auto.internal:29022): on response callback started
2025-11-01 16:44:52,133 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 65f65905-7d08-4f5f-82be-81e120013b09, ghrun-tvlw7frnpa.auto.internal:29022): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:44:52,133 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 65f65905-7d08-4f5f-82be-81e120013b09, ghrun-tvlw7frnpa.auto.internal:29022): on response callback success
2025-11-01 16:44:52,135 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_0
2025-11-01 16:44:52,135 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_0: send console operation, token None
2025-11-01 16:44:52,140 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_0: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_0&cmstid=72057594037936131&txid=1762015492134927&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:44:52,151 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_0: wait console operation done
2025-11-01 16:44:52,656 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_0: console operation done
2025-11-01 16:44:52,657 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_0: wait tenant gone
2025-11-01 16:44:52,660 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_0: tenant gone
2025-11-01 16:44:55,667 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:29022/1
2025-11-01 16:44:56,197 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:44:56,197 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17598
2025-11-01 16:44:56,198 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15839
2025-11-01 16:44:56,198 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14631
2025-11-01 16:44:56,198 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19068
2025-11-01 16:44:56,199 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17863
2025-11-01 16:44:56,199 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:44:56,199 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_1 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_pw4zmh9t.log --grpc-port=17598 --mon-port=15839 --ic-port=14631
2025-11-01 16:44:56,200 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:44:56,200 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_1 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_pw4zmh9t.log --grpc-port=17598 --mon-port=15839 --ic-port=14631
2025-11-01 16:44:56,200 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_1', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_pw4zmh9t.log', '--grpc-port=17598', '--mon-port=15839', '--ic-port=14631')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-11-01 16:44:56,221 - DEBUG - ya.test - execute: Command pid: 3019180
2025-11-01 16:44:56,231 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:17598/2
2025-11-01 16:44:56,233 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.20
2025-11-01 16:44:56,234 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.20
2025-11-01 16:44:56,234 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:44:56,241 - DEBUG - ya.test - execute: Command pid: 3019192
2025-11-01 16:44:57,721 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) rc: 0
2025-11-01 16:44:57,721 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) elapsed time (sec): 1.475372076034546
2025-11-01 16:44:57,721 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) idrss: 0
2025-11-01 16:44:57,721 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) inblock: 0
2025-11-01 16:44:57,722 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) isrss: 0
2025-11-01 16:44:57,732 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) ixrss: 0
2025-11-01 16:44:57,732 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) majflt: 0
2025-11-01 16:44:57,732 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) maxrss: 538512
2025-11-01 16:44:57,732 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) minflt: 16131
2025-11-01 16:44:57,732 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) msgrcv: 0
2025-11-01 16:44:57,733 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) msgsnd: 0
2025-11-01 16:44:57,733 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) nivcsw: 245
2025-11-01 16:44:57,734 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) nsignals: 0
2025-11-01 16:44:57,734 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) nswap: 0
2025-11-01 16:44:57,734 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) nvcsw: 31
2025-11-01 16:44:57,734 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) oublock: 8
2025-11-01 16:44:57,734 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) stime: 0.874918
2025-11-01 16:44:57,743 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) utime: 0.10976
2025-11-01 16:44:57,743 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) wtime: 1.482
2025-11-01 16:44:57,743 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:44:57,751 - DEBUG - ya.test - _save_outputs: Command (pid 3019192) errors:

2025-11-01 16:44:57,751 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:44:59,764 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:44:59,765 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:44:59,769 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:44:59,771 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:44:59,781 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5ef929500 state=pending>
2025-11-01 16:44:59,785 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_1
2025-11-01 16:44:59,786 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:44:59,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 13593f79-3f4d-4efa-a918-953564b949cd, localhost:65199): creating call state
2025-11-01 16:44:59,787 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:44:59,819 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 13593f79-3f4d-4efa-a918-953564b949cd, localhost:65199): request = { database: "/Root/users/database_1" }
2025-11-01 16:44:59,821 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:44:59,862 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:44:59,863 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 13593f79-3f4d-4efa-a918-953564b949cd, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 17598 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50001 } self_location: "1" } } } }
2025-11-01 16:44:59,865 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_1: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:17598, location , ssl: False>]>
2025-11-01 16:44:59,879 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:44:59,890 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:44:59,891 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:44:59,892 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6972ef32-4c97-496e-9431-7c8690dda603, ghrun-tvlw7frnpa.auto.internal:17598): creating call state
2025-11-01 16:44:59,892 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6972ef32-4c97-496e-9431-7c8690dda603, ghrun-tvlw7frnpa.auto.internal:17598): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:44:59,896 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6972ef32-4c97-496e-9431-7c8690dda603, ghrun-tvlw7frnpa.auto.internal:17598): on response callback started
2025-11-01 16:44:59,897 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6972ef32-4c97-496e-9431-7c8690dda603, ghrun-tvlw7frnpa.auto.internal:17598): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=YzA4MGI5NDctZmZkMmIyNzYtNWJmN2Y1MTctYTJlOTMxNWU=" } } } }
2025-11-01 16:44:59,902 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:44:59,903 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:44:59,903 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 1d296c5a-696b-4ada-bd68-c61f28468327, ghrun-tvlw7frnpa.auto.internal:17598): creating call state
2025-11-01 16:44:59,905 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 1d296c5a-696b-4ada-bd68-c61f28468327, ghrun-tvlw7frnpa.auto.internal:17598): request = { session_id: "ydb://session/3?node_id=50001&id=YzA4MGI5NDctZmZkMmIyNzYtNWJmN2Y1MTctYTJlOTMxNWU=" path: "/Root/users/database_1/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:44:59,904 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6972ef32-4c97-496e-9431-7c8690dda603, ghrun-tvlw7frnpa.auto.internal:17598): on response callback success
2025-11-01 16:44:59,941 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 1d296c5a-696b-4ada-bd68-c61f28468327, ghrun-tvlw7frnpa.auto.internal:17598): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:44:59,942 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:44:59,943 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 5c77b9a9-04e4-4c4b-a112-db04c2415a93, ghrun-tvlw7frnpa.auto.internal:17598): creating call state
2025-11-01 16:44:59,944 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 5c77b9a9-04e4-4c4b-a112-db04c2415a93, ghrun-tvlw7frnpa.auto.internal:17598): request = { session_id: "ydb://session/3?node_id=50001&id=YzA4MGI5NDctZmZkMmIyNzYtNWJmN2Y1MTctYTJlOTMxNWU=" path: "/Root/users/database_1/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:44:59,972 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 5c77b9a9-04e4-4c4b-a112-db04c2415a93, ghrun-tvlw7frnpa.auto.internal:17598): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:00,024 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:45:00,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 702efe74-a7c4-426f-a727-4e3c93157feb, ghrun-tvlw7frnpa.auto.internal:17598): creating call state
2025-11-01 16:45:00,026 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 702efe74-a7c4-426f-a727-4e3c93157feb, ghrun-tvlw7frnpa.auto.internal:17598): request = { session_id: "ydb://session/3?node_id=50001&id=YzA4MGI5NDctZmZkMmIyNzYtNWJmN2Y1MTctYTJlOTMxNWU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_1\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_1\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:00,059 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 702efe74-a7c4-426f-a727-4e3c93157feb, ghrun-tvlw7frnpa.auto.internal:17598): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:45:00,060 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:45:00,067 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 68ee1d1e-7c43-4b72-9bdf-dcb43a077195, ghrun-tvlw7frnpa.auto.internal:17598): creating call state
2025-11-01 16:45:00,068 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 68ee1d1e-7c43-4b72-9bdf-dcb43a077195, ghrun-tvlw7frnpa.auto.internal:17598): request = { session_id: "ydb://session/3?node_id=50001&id=YzA4MGI5NDctZmZkMmIyNzYtNWJmN2Y1MTctYTJlOTMxNWU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:00,111 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 68ee1d1e-7c43-4b72-9bdf-dcb43a077195, ghrun-tvlw7frnpa.auto.internal:17598): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:45:00,112 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:45:00,113 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 7683be3d-3bb5-4a4e-8360-5b51055df63c, ghrun-tvlw7frnpa.auto.internal:17598): creating call state
2025-11-01 16:45:00,114 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 7683be3d-3bb5-4a4e-8360-5b51055df63c, ghrun-tvlw7frnpa.auto.internal:17598): request = { session_id: "ydb://session/3?node_id=50001&id=YzA4MGI5NDctZmZkMmIyNzYtNWJmN2Y1MTctYTJlOTMxNWU=" path: "/Root/users/database_1/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:00,134 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 7683be3d-3bb5-4a4e-8360-5b51055df63c, ghrun-tvlw7frnpa.auto.internal:17598): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:00,148 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:45:00,152 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:45:00,152 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:45:00,152 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:45:00,153 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:45:00,154 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5ef92b7b0>, reason: session-pool-terminated
2025-11-01 16:45:00,154 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5ef92b7b0> is no longer active. Current active count 0.
2025-11-01 16:45:00,154 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, dc73d0e3-1bd0-42d7-854f-62a003183b1e, ghrun-tvlw7frnpa.auto.internal:17598): creating call state
2025-11-01 16:45:00,155 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, dc73d0e3-1bd0-42d7-854f-62a003183b1e, ghrun-tvlw7frnpa.auto.internal:17598): request = { session_id: "ydb://session/3?node_id=50001&id=YzA4MGI5NDctZmZkMmIyNzYtNWJmN2Y1MTctYTJlOTMxNWU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:00,156 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5ef92b7b0>
2025-11-01 16:45:00,157 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:45:00,157 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:45:00,157 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:45:00,157 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:00,158 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:17598
2025-11-01 16:45:00,158 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:45:00,158 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_1
2025-11-01 16:45:00,158 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_1: send console operation, token None
2025-11-01 16:45:00,163 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, dc73d0e3-1bd0-42d7-854f-62a003183b1e, ghrun-tvlw7frnpa.auto.internal:17598): on response callback started
2025-11-01 16:45:00,164 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, dc73d0e3-1bd0-42d7-854f-62a003183b1e, ghrun-tvlw7frnpa.auto.internal:17598): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:00,164 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, dc73d0e3-1bd0-42d7-854f-62a003183b1e, ghrun-tvlw7frnpa.auto.internal:17598): on response callback success
2025-11-01 16:45:00,167 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_1: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_1&cmstid=72057594037936131&txid=1762015500161250&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:45:00,168 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_1: wait console operation done
2025-11-01 16:45:00,681 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_1: console operation done
2025-11-01 16:45:00,681 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_1: wait tenant gone
2025-11-01 16:45:00,691 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_1: tenant gone
2025-11-01 16:45:03,816 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:17598/2
2025-11-01 16:45:04,337 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:45:04,338 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62513
2025-11-01 16:45:04,338 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5071
2025-11-01 16:45:04,339 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24885
2025-11-01 16:45:04,339 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2128
2025-11-01 16:45:04,340 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22189
2025-11-01 16:45:04,340 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:04,342 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_2 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_enfhp0wv.log --grpc-port=62513 --mon-port=5071 --ic-port=24885
2025-11-01 16:45:04,349 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:04,349 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_2 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_enfhp0wv.log --grpc-port=62513 --mon-port=5071 --ic-port=24885
2025-11-01 16:45:04,350 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_2', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_enfhp0wv.log', '--grpc-port=62513', '--mon-port=5071', '--ic-port=24885')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-11-01 16:45:04,355 - DEBUG - ya.test - execute: Command pid: 3025065
2025-11-01 16:45:04,355 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:62513/3
2025-11-01 16:45:04,356 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.21
2025-11-01 16:45:04,357 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.21
2025-11-01 16:45:04,366 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:45:04,375 - DEBUG - ya.test - execute: Command pid: 3025107
2025-11-01 16:45:04,983 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) rc: 0
2025-11-01 16:45:04,985 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) elapsed time (sec): 0.6076157093048096
2025-11-01 16:45:04,985 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) idrss: 0
2025-11-01 16:45:04,986 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) inblock: 0
2025-11-01 16:45:04,986 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) isrss: 0
2025-11-01 16:45:04,986 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) ixrss: 0
2025-11-01 16:45:04,986 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) majflt: 0
2025-11-01 16:45:04,986 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) maxrss: 538512
2025-11-01 16:45:04,986 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) minflt: 16131
2025-11-01 16:45:04,987 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) msgrcv: 0
2025-11-01 16:45:04,987 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) msgsnd: 0
2025-11-01 16:45:04,987 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) nivcsw: 112
2025-11-01 16:45:04,988 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) nsignals: 0
2025-11-01 16:45:04,988 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) nswap: 0
2025-11-01 16:45:04,988 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) nvcsw: 18
2025-11-01 16:45:04,988 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) oublock: 8
2025-11-01 16:45:04,988 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) stime: 0.36738299999999996
2025-11-01 16:45:04,989 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) utime: 0.12515199999999999
2025-11-01 16:45:04,989 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) wtime: 0.616
2025-11-01 16:45:04,989 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:45:04,989 - DEBUG - ya.test - _save_outputs: Command (pid 3025107) errors:

2025-11-01 16:45:04,990 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 3 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:45:07,001 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:07,007 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:45:07,035 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:07,008 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:45:07,052 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_2
2025-11-01 16:45:07,052 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5ef958290 state=pending>
2025-11-01 16:45:07,053 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 121cf5c4-a508-4055-b474-950c6d82913d, localhost:65199): creating call state
2025-11-01 16:45:07,053 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:45:07,087 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 121cf5c4-a508-4055-b474-950c6d82913d, localhost:65199): request = { database: "/Root/users/database_2" }
2025-11-01 16:45:07,088 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,090 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,096 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 121cf5c4-a508-4055-b474-950c6d82913d, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 62513 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50002 } self_location: "1" } } } }
2025-11-01 16:45:07,104 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_2: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:62513, location , ssl: False>]>
2025-11-01 16:45:07,108 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:07,097 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:45:07,122 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:07,123 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,135 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, e936d08a-a6f0-4271-8c4a-9841498a7d1b, ghrun-tvlw7frnpa.auto.internal:62513): creating call state
2025-11-01 16:45:07,135 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, e936d08a-a6f0-4271-8c4a-9841498a7d1b, ghrun-tvlw7frnpa.auto.internal:62513): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:07,143 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e936d08a-a6f0-4271-8c4a-9841498a7d1b, ghrun-tvlw7frnpa.auto.internal:62513): on response callback started
2025-11-01 16:45:07,143 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, e936d08a-a6f0-4271-8c4a-9841498a7d1b, ghrun-tvlw7frnpa.auto.internal:62513): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=NmZkYmM1ZTktYWMxNTJkYTEtMjA4MzRjY2YtYmQxMmRlMQ==" } } } }
2025-11-01 16:45:07,144 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e936d08a-a6f0-4271-8c4a-9841498a7d1b, ghrun-tvlw7frnpa.auto.internal:62513): on response callback success
2025-11-01 16:45:07,144 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,144 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,145 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 305e39ac-5d8b-4b99-83c2-ca2691a2d00d, ghrun-tvlw7frnpa.auto.internal:62513): creating call state
2025-11-01 16:45:07,145 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 305e39ac-5d8b-4b99-83c2-ca2691a2d00d, ghrun-tvlw7frnpa.auto.internal:62513): request = { session_id: "ydb://session/3?node_id=50002&id=NmZkYmM1ZTktYWMxNTJkYTEtMjA4MzRjY2YtYmQxMmRlMQ==" path: "/Root/users/database_2/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:07,199 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 305e39ac-5d8b-4b99-83c2-ca2691a2d00d, ghrun-tvlw7frnpa.auto.internal:62513): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:07,199 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,201 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 45dbd3c5-4e0e-4006-bfaf-67ce3041b502, ghrun-tvlw7frnpa.auto.internal:62513): creating call state
2025-11-01 16:45:07,204 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 45dbd3c5-4e0e-4006-bfaf-67ce3041b502, ghrun-tvlw7frnpa.auto.internal:62513): request = { session_id: "ydb://session/3?node_id=50002&id=NmZkYmM1ZTktYWMxNTJkYTEtMjA4MzRjY2YtYmQxMmRlMQ==" path: "/Root/users/database_2/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:07,269 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 45dbd3c5-4e0e-4006-bfaf-67ce3041b502, ghrun-tvlw7frnpa.auto.internal:62513): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:07,269 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,270 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 5f3ddf1c-491a-4295-b96e-3debb75123f5, ghrun-tvlw7frnpa.auto.internal:62513): creating call state
2025-11-01 16:45:07,271 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 5f3ddf1c-491a-4295-b96e-3debb75123f5, ghrun-tvlw7frnpa.auto.internal:62513): request = { session_id: "ydb://session/3?node_id=50002&id=NmZkYmM1ZTktYWMxNTJkYTEtMjA4MzRjY2YtYmQxMmRlMQ==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_2\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_2\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:07,325 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 5f3ddf1c-491a-4295-b96e-3debb75123f5, ghrun-tvlw7frnpa.auto.internal:62513): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:45:07,326 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,345 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b86b4f4b-f8f1-485f-9d89-2359717f12a9, ghrun-tvlw7frnpa.auto.internal:62513): creating call state
2025-11-01 16:45:07,347 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b86b4f4b-f8f1-485f-9d89-2359717f12a9, ghrun-tvlw7frnpa.auto.internal:62513): request = { session_id: "ydb://session/3?node_id=50002&id=NmZkYmM1ZTktYWMxNTJkYTEtMjA4MzRjY2YtYmQxMmRlMQ==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:07,390 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b86b4f4b-f8f1-485f-9d89-2359717f12a9, ghrun-tvlw7frnpa.auto.internal:62513): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:45:07,412 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,413 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, bcd53d69-9cf4-4ab7-8e4d-f570b694b310, ghrun-tvlw7frnpa.auto.internal:62513): creating call state
2025-11-01 16:45:07,414 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, bcd53d69-9cf4-4ab7-8e4d-f570b694b310, ghrun-tvlw7frnpa.auto.internal:62513): request = { session_id: "ydb://session/3?node_id=50002&id=NmZkYmM1ZTktYWMxNTJkYTEtMjA4MzRjY2YtYmQxMmRlMQ==" path: "/Root/users/database_2/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:07,475 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, bcd53d69-9cf4-4ab7-8e4d-f570b694b310, ghrun-tvlw7frnpa.auto.internal:62513): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:07,488 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,488 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:45:07,489 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:45:07,489 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:45:07,489 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5f0fbf8a0>, reason: session-pool-terminated
2025-11-01 16:45:07,489 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5f0fbf8a0> is no longer active. Current active count 0.
2025-11-01 16:45:07,490 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 9de85be6-9de8-4230-95b3-56731266dfd5, ghrun-tvlw7frnpa.auto.internal:62513): creating call state
2025-11-01 16:45:07,490 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 9de85be6-9de8-4230-95b3-56731266dfd5, ghrun-tvlw7frnpa.auto.internal:62513): request = { session_id: "ydb://session/3?node_id=50002&id=NmZkYmM1ZTktYWMxNTJkYTEtMjA4MzRjY2YtYmQxMmRlMQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:07,491 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:45:07,503 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 9de85be6-9de8-4230-95b3-56731266dfd5, ghrun-tvlw7frnpa.auto.internal:62513): on response callback started
2025-11-01 16:45:07,514 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 9de85be6-9de8-4230-95b3-56731266dfd5, ghrun-tvlw7frnpa.auto.internal:62513): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:07,514 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 9de85be6-9de8-4230-95b3-56731266dfd5, ghrun-tvlw7frnpa.auto.internal:62513): on response callback success
2025-11-01 16:45:07,515 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5f0fbf8a0>
2025-11-01 16:45:07,515 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:45:07,515 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:45:07,515 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:45:07,516 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:07,516 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:62513
2025-11-01 16:45:07,521 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:45:07,531 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_2
2025-11-01 16:45:07,532 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_2: send console operation, token None
2025-11-01 16:45:07,538 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_2: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_2&cmstid=72057594037936131&txid=1762015507534991&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:45:07,548 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_2: wait console operation done
2025-11-01 16:45:08,055 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_2: console operation done
2025-11-01 16:45:08,056 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_2: wait tenant gone
2025-11-01 16:45:08,060 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_2: tenant gone
2025-11-01 16:45:11,063 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:62513/3
2025-11-01 16:45:11,590 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:45:11,600 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11096
2025-11-01 16:45:11,601 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29326
2025-11-01 16:45:11,601 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4707
2025-11-01 16:45:11,602 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8519
2025-11-01 16:45:11,602 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3267
2025-11-01 16:45:11,603 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:11,603 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_3 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_1ahrvnhn.log --grpc-port=11096 --mon-port=29326 --ic-port=4707
2025-11-01 16:45:11,603 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:11,603 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_3 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_1ahrvnhn.log --grpc-port=11096 --mon-port=29326 --ic-port=4707
2025-11-01 16:45:11,605 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_3', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_1ahrvnhn.log', '--grpc-port=11096', '--mon-port=29326', '--ic-port=4707')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4' (no wait)
2025-11-01 16:45:11,645 - DEBUG - ya.test - execute: Command pid: 3030065
2025-11-01 16:45:11,646 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:11096/4
2025-11-01 16:45:11,647 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.22
2025-11-01 16:45:11,648 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.22
2025-11-01 16:45:11,648 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:45:11,659 - DEBUG - ya.test - execute: Command pid: 3030066
2025-11-01 16:45:12,311 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) rc: 0
2025-11-01 16:45:12,312 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) elapsed time (sec): 0.6519689559936523
2025-11-01 16:45:12,312 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) idrss: 0
2025-11-01 16:45:12,312 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) inblock: 0
2025-11-01 16:45:12,312 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) isrss: 0
2025-11-01 16:45:12,313 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) ixrss: 0
2025-11-01 16:45:12,313 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) majflt: 0
2025-11-01 16:45:12,313 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) maxrss: 538512
2025-11-01 16:45:12,313 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) minflt: 16131
2025-11-01 16:45:12,313 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) msgrcv: 0
2025-11-01 16:45:12,313 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) msgsnd: 0
2025-11-01 16:45:12,313 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) nivcsw: 111
2025-11-01 16:45:12,314 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) nsignals: 0
2025-11-01 16:45:12,314 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) nswap: 0
2025-11-01 16:45:12,314 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) nvcsw: 24
2025-11-01 16:45:12,314 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) oublock: 8
2025-11-01 16:45:12,314 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) stime: 0.41278299999999996
2025-11-01 16:45:12,315 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) utime: 0.119658
2025-11-01 16:45:12,315 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) wtime: 0.663
2025-11-01 16:45:12,315 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:45:12,315 - DEBUG - ya.test - _save_outputs: Command (pid 3030066) errors:

2025-11-01 16:45:12,316 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 4 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:45:18,334 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:18,336 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:45:18,337 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:45:18,342 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:18,349 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5f0f5e860 state=pending>
2025-11-01 16:45:18,361 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:45:18,366 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_3
2025-11-01 16:45:18,367 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,372 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b12bf182-fe5c-42a0-876d-fe53e5cfdd3a, localhost:65199): creating call state
2025-11-01 16:45:18,383 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,384 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b12bf182-fe5c-42a0-876d-fe53e5cfdd3a, localhost:65199): request = { database: "/Root/users/database_3" }
2025-11-01 16:45:18,387 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:45:18,391 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b12bf182-fe5c-42a0-876d-fe53e5cfdd3a, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 11096 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50003 } self_location: "1" } } } }
2025-11-01 16:45:18,393 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_3: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:11096, location , ssl: False>]>
2025-11-01 16:45:18,394 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:18,402 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:18,402 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,405 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 089392ff-5a8f-4055-ac9a-e02a84db0097, ghrun-tvlw7frnpa.auto.internal:11096): creating call state
2025-11-01 16:45:18,410 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 089392ff-5a8f-4055-ac9a-e02a84db0097, ghrun-tvlw7frnpa.auto.internal:11096): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:18,413 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 089392ff-5a8f-4055-ac9a-e02a84db0097, ghrun-tvlw7frnpa.auto.internal:11096): on response callback started
2025-11-01 16:45:18,414 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 089392ff-5a8f-4055-ac9a-e02a84db0097, ghrun-tvlw7frnpa.auto.internal:11096): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=NDJjODMyMzgtYzk4YjFlYTYtNDAyMTJkODctNGRkMDljZWU=" } } } }
2025-11-01 16:45:18,423 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,426 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,427 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, f4294554-a3cb-4a87-9e50-6302ebae3489, ghrun-tvlw7frnpa.auto.internal:11096): creating call state
2025-11-01 16:45:18,428 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 089392ff-5a8f-4055-ac9a-e02a84db0097, ghrun-tvlw7frnpa.auto.internal:11096): on response callback success
2025-11-01 16:45:18,429 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, f4294554-a3cb-4a87-9e50-6302ebae3489, ghrun-tvlw7frnpa.auto.internal:11096): request = { session_id: "ydb://session/3?node_id=50003&id=NDJjODMyMzgtYzk4YjFlYTYtNDAyMTJkODctNGRkMDljZWU=" path: "/Root/users/database_3/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:18,490 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, f4294554-a3cb-4a87-9e50-6302ebae3489, ghrun-tvlw7frnpa.auto.internal:11096): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:18,499 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,500 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 8668cc5c-877b-44fd-a45c-48a5d40fc932, ghrun-tvlw7frnpa.auto.internal:11096): creating call state
2025-11-01 16:45:18,501 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 8668cc5c-877b-44fd-a45c-48a5d40fc932, ghrun-tvlw7frnpa.auto.internal:11096): request = { session_id: "ydb://session/3?node_id=50003&id=NDJjODMyMzgtYzk4YjFlYTYtNDAyMTJkODctNGRkMDljZWU=" path: "/Root/users/database_3/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:18,561 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 8668cc5c-877b-44fd-a45c-48a5d40fc932, ghrun-tvlw7frnpa.auto.internal:11096): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:18,562 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,562 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 08c5e665-ca5b-465f-9773-19ef656e3970, ghrun-tvlw7frnpa.auto.internal:11096): creating call state
2025-11-01 16:45:18,563 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 08c5e665-ca5b-465f-9773-19ef656e3970, ghrun-tvlw7frnpa.auto.internal:11096): request = { session_id: "ydb://session/3?node_id=50003&id=NDJjODMyMzgtYzk4YjFlYTYtNDAyMTJkODctNGRkMDljZWU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_3\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_3\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:18,595 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 08c5e665-ca5b-465f-9773-19ef656e3970, ghrun-tvlw7frnpa.auto.internal:11096): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:45:18,597 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,597 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e6610a8e-7dc8-41c1-85ae-8160b4896794, ghrun-tvlw7frnpa.auto.internal:11096): creating call state
2025-11-01 16:45:18,607 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e6610a8e-7dc8-41c1-85ae-8160b4896794, ghrun-tvlw7frnpa.auto.internal:11096): request = { session_id: "ydb://session/3?node_id=50003&id=NDJjODMyMzgtYzk4YjFlYTYtNDAyMTJkODctNGRkMDljZWU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:18,644 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e6610a8e-7dc8-41c1-85ae-8160b4896794, ghrun-tvlw7frnpa.auto.internal:11096): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:45:18,644 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,645 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 829252ee-8cc0-47ae-b325-670136406eb4, ghrun-tvlw7frnpa.auto.internal:11096): creating call state
2025-11-01 16:45:18,646 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 829252ee-8cc0-47ae-b325-670136406eb4, ghrun-tvlw7frnpa.auto.internal:11096): request = { session_id: "ydb://session/3?node_id=50003&id=NDJjODMyMzgtYzk4YjFlYTYtNDAyMTJkODctNGRkMDljZWU=" path: "/Root/users/database_3/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:18,696 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 829252ee-8cc0-47ae-b325-670136406eb4, ghrun-tvlw7frnpa.auto.internal:11096): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:18,707 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,707 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:45:18,707 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:45:18,707 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:45:18,708 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:45:18,712 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5f15ef800>, reason: session-pool-terminated
2025-11-01 16:45:18,712 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5f15ef800> is no longer active. Current active count 0.
2025-11-01 16:45:18,713 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 3e0fe702-e7aa-4643-af13-761f10b92c5d, ghrun-tvlw7frnpa.auto.internal:11096): creating call state
2025-11-01 16:45:18,713 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 3e0fe702-e7aa-4643-af13-761f10b92c5d, ghrun-tvlw7frnpa.auto.internal:11096): request = { session_id: "ydb://session/3?node_id=50003&id=NDJjODMyMzgtYzk4YjFlYTYtNDAyMTJkODctNGRkMDljZWU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:18,719 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3e0fe702-e7aa-4643-af13-761f10b92c5d, ghrun-tvlw7frnpa.auto.internal:11096): on response callback started
2025-11-01 16:45:18,727 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 3e0fe702-e7aa-4643-af13-761f10b92c5d, ghrun-tvlw7frnpa.auto.internal:11096): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:18,727 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3e0fe702-e7aa-4643-af13-761f10b92c5d, ghrun-tvlw7frnpa.auto.internal:11096): on response callback success
2025-11-01 16:45:18,727 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5f15ef800>
2025-11-01 16:45:18,728 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:45:18,743 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:45:18,743 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:45:18,744 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:18,744 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:11096
2025-11-01 16:45:18,745 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:45:18,752 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_3
2025-11-01 16:45:18,757 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_3: send console operation, token None
2025-11-01 16:45:18,763 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_3: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_3&cmstid=72057594037936131&txid=1762015518760420&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:45:18,775 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_3: wait console operation done
2025-11-01 16:45:19,280 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_3: console operation done
2025-11-01 16:45:19,283 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_3: wait tenant gone
2025-11-01 16:45:19,286 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_3: tenant gone
2025-11-01 16:45:22,287 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11096/4
2025-11-01 16:45:22,804 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:45:22,809 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1921
2025-11-01 16:45:22,810 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14486
2025-11-01 16:45:22,810 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30075
2025-11-01 16:45:22,811 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62068
2025-11-01 16:45:22,812 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28611
2025-11-01 16:45:22,823 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:22,824 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_4 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_bkzl5vem.log --grpc-port=1921 --mon-port=14486 --ic-port=30075
2025-11-01 16:45:22,849 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:22,872 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_4 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_bkzl5vem.log --grpc-port=1921 --mon-port=14486 --ic-port=30075
2025-11-01 16:45:22,873 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_4', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_bkzl5vem.log', '--grpc-port=1921', '--mon-port=14486', '--ic-port=30075')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5' (no wait)
2025-11-01 16:45:22,876 - DEBUG - ya.test - execute: Command pid: 3037015
2025-11-01 16:45:22,891 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:1921/5
2025-11-01 16:45:22,894 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.23
2025-11-01 16:45:22,912 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.23
2025-11-01 16:45:22,913 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:45:22,914 - DEBUG - ya.test - execute: Command pid: 3037049
2025-11-01 16:45:23,491 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) rc: 0
2025-11-01 16:45:23,492 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) elapsed time (sec): 0.5755579471588135
2025-11-01 16:45:23,492 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) idrss: 0
2025-11-01 16:45:23,492 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) inblock: 0
2025-11-01 16:45:23,492 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) isrss: 0
2025-11-01 16:45:23,492 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) ixrss: 0
2025-11-01 16:45:23,493 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) majflt: 0
2025-11-01 16:45:23,493 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) maxrss: 538512
2025-11-01 16:45:23,493 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) minflt: 16131
2025-11-01 16:45:23,493 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) msgrcv: 0
2025-11-01 16:45:23,493 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) msgsnd: 0
2025-11-01 16:45:23,493 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) nivcsw: 101
2025-11-01 16:45:23,502 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) nsignals: 0
2025-11-01 16:45:23,503 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) nswap: 0
2025-11-01 16:45:23,503 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) nvcsw: 21
2025-11-01 16:45:23,503 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) oublock: 8
2025-11-01 16:45:23,507 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) stime: 0.37705299999999997
2025-11-01 16:45:23,507 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) utime: 0.11390099999999999
2025-11-01 16:45:23,508 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) wtime: 0.577
2025-11-01 16:45:23,509 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:45:23,510 - DEBUG - ya.test - _save_outputs: Command (pid 3037049) errors:

2025-11-01 16:45:23,510 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 5 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:45:25,519 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:25,539 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:45:25,539 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:25,547 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:45:25,548 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5ef952b30 state=pending>
2025-11-01 16:45:25,548 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:45:25,548 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,548 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,549 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:45:25,583 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_4
2025-11-01 16:45:25,584 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d9ad206b-7c6d-4f03-be88-99f79f6b17ac, localhost:65199): creating call state
2025-11-01 16:45:25,584 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d9ad206b-7c6d-4f03-be88-99f79f6b17ac, localhost:65199): request = { database: "/Root/users/database_4" }
2025-11-01 16:45:25,591 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d9ad206b-7c6d-4f03-be88-99f79f6b17ac, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 1921 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: 50004 } self_location: "1" } } } }
2025-11-01 16:45:25,592 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_4: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:1921, location , ssl: False>]>
2025-11-01 16:45:25,592 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:25,612 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:25,612 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,614 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 803c8ef9-ae79-4802-ac32-84acac883c00, ghrun-tvlw7frnpa.auto.internal:1921): creating call state
2025-11-01 16:45:25,619 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 803c8ef9-ae79-4802-ac32-84acac883c00, ghrun-tvlw7frnpa.auto.internal:1921): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:25,622 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 803c8ef9-ae79-4802-ac32-84acac883c00, ghrun-tvlw7frnpa.auto.internal:1921): on response callback started
2025-11-01 16:45:25,623 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 803c8ef9-ae79-4802-ac32-84acac883c00, ghrun-tvlw7frnpa.auto.internal:1921): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50004&id=Y2JjYTE4MjEtYmEzNGU3ZTEtMzkzZDlhOGItYzFlNTA4MTY=" } } } }
2025-11-01 16:45:25,624 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,625 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,625 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 803c8ef9-ae79-4802-ac32-84acac883c00, ghrun-tvlw7frnpa.auto.internal:1921): on response callback success
2025-11-01 16:45:25,629 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 9d808df8-f7c9-4937-b64f-500ff9f8d2d2, ghrun-tvlw7frnpa.auto.internal:1921): creating call state
2025-11-01 16:45:25,630 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 9d808df8-f7c9-4937-b64f-500ff9f8d2d2, ghrun-tvlw7frnpa.auto.internal:1921): request = { session_id: "ydb://session/3?node_id=50004&id=Y2JjYTE4MjEtYmEzNGU3ZTEtMzkzZDlhOGItYzFlNTA4MTY=" path: "/Root/users/database_4/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:25,687 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 9d808df8-f7c9-4937-b64f-500ff9f8d2d2, ghrun-tvlw7frnpa.auto.internal:1921): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:25,688 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,689 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, d43a9500-e87c-40af-a7fb-76a18df1a40e, ghrun-tvlw7frnpa.auto.internal:1921): creating call state
2025-11-01 16:45:25,691 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, d43a9500-e87c-40af-a7fb-76a18df1a40e, ghrun-tvlw7frnpa.auto.internal:1921): request = { session_id: "ydb://session/3?node_id=50004&id=Y2JjYTE4MjEtYmEzNGU3ZTEtMzkzZDlhOGItYzFlNTA4MTY=" path: "/Root/users/database_4/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:25,723 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, d43a9500-e87c-40af-a7fb-76a18df1a40e, ghrun-tvlw7frnpa.auto.internal:1921): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:25,723 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,724 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6873ad5e-c524-4b4c-b749-c05a01bc360a, ghrun-tvlw7frnpa.auto.internal:1921): creating call state
2025-11-01 16:45:25,725 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6873ad5e-c524-4b4c-b749-c05a01bc360a, ghrun-tvlw7frnpa.auto.internal:1921): request = { session_id: "ydb://session/3?node_id=50004&id=Y2JjYTE4MjEtYmEzNGU3ZTEtMzkzZDlhOGItYzFlNTA4MTY=" 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_4\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_4\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:25,755 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6873ad5e-c524-4b4c-b749-c05a01bc360a, ghrun-tvlw7frnpa.auto.internal:1921): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:45:25,756 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4f297280-f867-48be-8662-1bcdd5af8b1f, ghrun-tvlw7frnpa.auto.internal:1921): creating call state
2025-11-01 16:45:25,762 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4f297280-f867-48be-8662-1bcdd5af8b1f, ghrun-tvlw7frnpa.auto.internal:1921): request = { session_id: "ydb://session/3?node_id=50004&id=Y2JjYTE4MjEtYmEzNGU3ZTEtMzkzZDlhOGItYzFlNTA4MTY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:25,798 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4f297280-f867-48be-8662-1bcdd5af8b1f, ghrun-tvlw7frnpa.auto.internal:1921): 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_4" } } 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_4" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:45:25,799 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,800 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 8749b576-520c-458a-9d10-5b7573e27a51, ghrun-tvlw7frnpa.auto.internal:1921): creating call state
2025-11-01 16:45:25,819 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 8749b576-520c-458a-9d10-5b7573e27a51, ghrun-tvlw7frnpa.auto.internal:1921): request = { session_id: "ydb://session/3?node_id=50004&id=Y2JjYTE4MjEtYmEzNGU3ZTEtMzkzZDlhOGItYzFlNTA4MTY=" path: "/Root/users/database_4/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:25,844 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 8749b576-520c-458a-9d10-5b7573e27a51, ghrun-tvlw7frnpa.auto.internal:1921): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:25,844 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,845 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:45:25,845 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:45:25,845 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:45:25,845 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5ef952180>, reason: session-pool-terminated
2025-11-01 16:45:25,845 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5ef952180> is no longer active. Current active count 0.
2025-11-01 16:45:25,846 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 3c8b4d8a-1358-423a-bc96-420a72a90c4d, ghrun-tvlw7frnpa.auto.internal:1921): creating call state
2025-11-01 16:45:25,846 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 3c8b4d8a-1358-423a-bc96-420a72a90c4d, ghrun-tvlw7frnpa.auto.internal:1921): request = { session_id: "ydb://session/3?node_id=50004&id=Y2JjYTE4MjEtYmEzNGU3ZTEtMzkzZDlhOGItYzFlNTA4MTY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:25,846 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:45:25,851 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3c8b4d8a-1358-423a-bc96-420a72a90c4d, ghrun-tvlw7frnpa.auto.internal:1921): on response callback started
2025-11-01 16:45:25,859 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 3c8b4d8a-1358-423a-bc96-420a72a90c4d, ghrun-tvlw7frnpa.auto.internal:1921): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:25,861 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3c8b4d8a-1358-423a-bc96-420a72a90c4d, ghrun-tvlw7frnpa.auto.internal:1921): on response callback success
2025-11-01 16:45:25,862 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5ef952180>
2025-11-01 16:45:25,864 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:45:25,872 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:45:25,873 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:45:25,878 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:25,882 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:1921
2025-11-01 16:45:25,900 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:45:25,902 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_4
2025-11-01 16:45:25,903 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_4: send console operation, token None
2025-11-01 16:45:25,916 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_4: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_4&cmstid=72057594037936131&txid=1762015525906714&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:45:25,923 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_4: wait console operation done
2025-11-01 16:45:26,427 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_4: console operation done
2025-11-01 16:45:26,428 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_4: wait tenant gone
2025-11-01 16:45:26,430 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_4: tenant gone
2025-11-01 16:45:27,431 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1921/5
2025-11-01 16:45:27,949 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:45:27,950 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3225
2025-11-01 16:45:27,950 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29759
2025-11-01 16:45:27,951 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29760
2025-11-01 16:45:27,951 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14452
2025-11-01 16:45:27,951 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7564
2025-11-01 16:45:27,953 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16014
2025-11-01 16:45:27,975 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:27,977 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_5 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6/logfile_uri5rel6.log --grpc-port=3225 --mon-port=29760 --ic-port=14452
2025-11-01 16:45:27,981 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:27,982 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_5 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6/logfile_uri5rel6.log --grpc-port=3225 --mon-port=29760 --ic-port=14452
2025-11-01 16:45:27,987 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_5', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6/logfile_uri5rel6.log', '--grpc-port=3225', '--mon-port=29760', '--ic-port=14452')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6' (no wait)
2025-11-01 16:45:27,988 - DEBUG - ya.test - execute: Command pid: 3040293
2025-11-01 16:45:27,990 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:3225/6
2025-11-01 16:45:27,991 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.24
2025-11-01 16:45:27,992 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.24
2025-11-01 16:45:27,992 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:45:27,993 - DEBUG - ya.test - execute: Command pid: 3040294
2025-11-01 16:45:28,887 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) rc: 0
2025-11-01 16:45:28,887 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) elapsed time (sec): 0.888068675994873
2025-11-01 16:45:28,887 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) idrss: 0
2025-11-01 16:45:28,895 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) inblock: 0
2025-11-01 16:45:28,895 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) isrss: 0
2025-11-01 16:45:28,895 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) ixrss: 0
2025-11-01 16:45:28,895 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) majflt: 0
2025-11-01 16:45:28,895 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) maxrss: 538512
2025-11-01 16:45:28,896 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) minflt: 16128
2025-11-01 16:45:28,896 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) msgrcv: 0
2025-11-01 16:45:28,896 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) msgsnd: 0
2025-11-01 16:45:28,896 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) nivcsw: 232
2025-11-01 16:45:28,896 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) nsignals: 0
2025-11-01 16:45:28,896 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) nswap: 0
2025-11-01 16:45:28,897 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) nvcsw: 20
2025-11-01 16:45:28,897 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) oublock: 8
2025-11-01 16:45:28,897 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) stime: 0.521771
2025-11-01 16:45:28,897 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) utime: 0.13006399999999999
2025-11-01 16:45:28,897 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) wtime: 0.89
2025-11-01 16:45:28,897 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:45:28,898 - DEBUG - ya.test - _save_outputs: Command (pid 3040294) errors:

2025-11-01 16:45:28,898 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 6 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:45:30,908 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:30,911 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:45:30,911 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:30,915 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:45:30,915 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5f17c0dd0 state=pending>
2025-11-01 16:45:30,915 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:45:30,915 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:30,916 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:30,923 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:45:30,926 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_5
2025-11-01 16:45:30,926 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6db4748f-96e5-4d68-89e1-eda48f6242b9, localhost:65199): creating call state
2025-11-01 16:45:30,927 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6db4748f-96e5-4d68-89e1-eda48f6242b9, localhost:65199): request = { database: "/Root/users/database_5" }
2025-11-01 16:45:30,930 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6db4748f-96e5-4d68-89e1-eda48f6242b9, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 3225 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: 50005 } self_location: "1" } } } }
2025-11-01 16:45:30,931 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_5: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:3225, location , ssl: False>]>
2025-11-01 16:45:30,931 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:30,940 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:30,940 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:30,941 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0fc808c2-f6f4-49df-8da6-65b60155ebcb, ghrun-tvlw7frnpa.auto.internal:3225): creating call state
2025-11-01 16:45:30,942 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0fc808c2-f6f4-49df-8da6-65b60155ebcb, ghrun-tvlw7frnpa.auto.internal:3225): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:30,946 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0fc808c2-f6f4-49df-8da6-65b60155ebcb, ghrun-tvlw7frnpa.auto.internal:3225): on response callback started
2025-11-01 16:45:30,947 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0fc808c2-f6f4-49df-8da6-65b60155ebcb, ghrun-tvlw7frnpa.auto.internal:3225): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50005&id=NjI5ZTVmNzctYzNhNjljYTQtMjM0ZjFkMzItM2MyZjY2YmU=" } } } }
2025-11-01 16:45:30,947 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:30,948 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:30,949 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, b344c001-13a4-45ea-8b59-e12933f1f3ff, ghrun-tvlw7frnpa.auto.internal:3225): creating call state
2025-11-01 16:45:30,954 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, b344c001-13a4-45ea-8b59-e12933f1f3ff, ghrun-tvlw7frnpa.auto.internal:3225): request = { session_id: "ydb://session/3?node_id=50005&id=NjI5ZTVmNzctYzNhNjljYTQtMjM0ZjFkMzItM2MyZjY2YmU=" path: "/Root/users/database_5/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:30,949 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0fc808c2-f6f4-49df-8da6-65b60155ebcb, ghrun-tvlw7frnpa.auto.internal:3225): on response callback success
2025-11-01 16:45:31,022 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, b344c001-13a4-45ea-8b59-e12933f1f3ff, ghrun-tvlw7frnpa.auto.internal:3225): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:31,023 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:31,024 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, f7c89fac-fc90-43e7-916f-e4c40c32d393, ghrun-tvlw7frnpa.auto.internal:3225): creating call state
2025-11-01 16:45:31,024 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, f7c89fac-fc90-43e7-916f-e4c40c32d393, ghrun-tvlw7frnpa.auto.internal:3225): request = { session_id: "ydb://session/3?node_id=50005&id=NjI5ZTVmNzctYzNhNjljYTQtMjM0ZjFkMzItM2MyZjY2YmU=" path: "/Root/users/database_5/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:31,061 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, f7c89fac-fc90-43e7-916f-e4c40c32d393, ghrun-tvlw7frnpa.auto.internal:3225): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:31,062 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:31,063 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6d4a7447-cb5b-4591-8350-b7a4945300e6, ghrun-tvlw7frnpa.auto.internal:3225): creating call state
2025-11-01 16:45:31,064 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6d4a7447-cb5b-4591-8350-b7a4945300e6, ghrun-tvlw7frnpa.auto.internal:3225): request = { session_id: "ydb://session/3?node_id=50005&id=NjI5ZTVmNzctYzNhNjljYTQtMjM0ZjFkMzItM2MyZjY2YmU=" 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_5\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_5\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:31,095 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6d4a7447-cb5b-4591-8350-b7a4945300e6, ghrun-tvlw7frnpa.auto.internal:3225): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:45:31,099 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:31,099 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, fdb3842e-bad2-4a81-a671-428e1e0bc8b5, ghrun-tvlw7frnpa.auto.internal:3225): creating call state
2025-11-01 16:45:31,106 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, fdb3842e-bad2-4a81-a671-428e1e0bc8b5, ghrun-tvlw7frnpa.auto.internal:3225): request = { session_id: "ydb://session/3?node_id=50005&id=NjI5ZTVmNzctYzNhNjljYTQtMjM0ZjFkMzItM2MyZjY2YmU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:31,144 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, fdb3842e-bad2-4a81-a671-428e1e0bc8b5, ghrun-tvlw7frnpa.auto.internal:3225): 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_5" } } 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_5" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:45:31,145 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:31,146 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 5a278f5c-543c-4829-976b-eab1b5457350, ghrun-tvlw7frnpa.auto.internal:3225): creating call state
2025-11-01 16:45:31,146 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 5a278f5c-543c-4829-976b-eab1b5457350, ghrun-tvlw7frnpa.auto.internal:3225): request = { session_id: "ydb://session/3?node_id=50005&id=NjI5ZTVmNzctYzNhNjljYTQtMjM0ZjFkMzItM2MyZjY2YmU=" path: "/Root/users/database_5/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:31,164 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 5a278f5c-543c-4829-976b-eab1b5457350, ghrun-tvlw7frnpa.auto.internal:3225): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:31,165 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:31,166 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:45:31,168 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:45:31,169 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:45:31,170 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5f17c2310>, reason: session-pool-terminated
2025-11-01 16:45:31,170 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5f17c2310> is no longer active. Current active count 0.
2025-11-01 16:45:31,170 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 322918e7-a4c7-40a7-920d-2f2c17c6e7f2, ghrun-tvlw7frnpa.auto.internal:3225): creating call state
2025-11-01 16:45:31,168 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:45:31,171 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 322918e7-a4c7-40a7-920d-2f2c17c6e7f2, ghrun-tvlw7frnpa.auto.internal:3225): request = { session_id: "ydb://session/3?node_id=50005&id=NjI5ZTVmNzctYzNhNjljYTQtMjM0ZjFkMzItM2MyZjY2YmU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:31,173 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 322918e7-a4c7-40a7-920d-2f2c17c6e7f2, ghrun-tvlw7frnpa.auto.internal:3225): on response callback started
2025-11-01 16:45:31,174 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 322918e7-a4c7-40a7-920d-2f2c17c6e7f2, ghrun-tvlw7frnpa.auto.internal:3225): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:31,174 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 322918e7-a4c7-40a7-920d-2f2c17c6e7f2, ghrun-tvlw7frnpa.auto.internal:3225): on response callback success
2025-11-01 16:45:31,175 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5f17c2310>
2025-11-01 16:45:31,175 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:45:31,176 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:45:31,177 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:45:31,177 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:31,177 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:3225
2025-11-01 16:45:31,178 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:45:31,181 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_5
2025-11-01 16:45:31,182 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_5: send console operation, token None
2025-11-01 16:45:31,188 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_5: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_5&cmstid=72057594037936131&txid=1762015531186139&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:45:31,189 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_5: wait console operation done
2025-11-01 16:45:31,703 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_5: console operation done
2025-11-01 16:45:31,703 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_5: wait tenant gone
2025-11-01 16:45:31,710 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_5: tenant gone
2025-11-01 16:45:32,711 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:3225/6
2025-11-01 16:45:33,229 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:45:33,230 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29328
2025-11-01 16:45:33,230 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32279
2025-11-01 16:45:33,230 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31894
2025-11-01 16:45:33,231 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22926
2025-11-01 16:45:33,232 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19110
2025-11-01 16:45:33,233 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:33,234 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_6 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_7/logfile_52ix_2w5.log --grpc-port=29328 --mon-port=32279 --ic-port=31894
2025-11-01 16:45:33,234 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:33,235 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_6 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_7/logfile_52ix_2w5.log --grpc-port=29328 --mon-port=32279 --ic-port=31894
2025-11-01 16:45:33,237 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_6', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_7/logfile_52ix_2w5.log', '--grpc-port=29328', '--mon-port=32279', '--ic-port=31894')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_7' (no wait)
2025-11-01 16:45:33,238 - DEBUG - ya.test - execute: Command pid: 3042805
2025-11-01 16:45:33,239 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:29328/7
2025-11-01 16:45:33,240 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.25
2025-11-01 16:45:33,242 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.25
2025-11-01 16:45:33,242 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:45:33,243 - DEBUG - ya.test - execute: Command pid: 3042806
2025-11-01 16:45:33,689 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) rc: 0
2025-11-01 16:45:33,689 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) elapsed time (sec): 0.4446439743041992
2025-11-01 16:45:33,690 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) idrss: 0
2025-11-01 16:45:33,690 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) inblock: 0
2025-11-01 16:45:33,690 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) isrss: 0
2025-11-01 16:45:33,690 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) ixrss: 0
2025-11-01 16:45:33,690 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) majflt: 1
2025-11-01 16:45:33,690 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) maxrss: 538512
2025-11-01 16:45:33,691 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) minflt: 16386
2025-11-01 16:45:33,691 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) msgrcv: 0
2025-11-01 16:45:33,691 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) msgsnd: 0
2025-11-01 16:45:33,691 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) nivcsw: 73
2025-11-01 16:45:33,691 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) nsignals: 0
2025-11-01 16:45:33,693 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) nswap: 0
2025-11-01 16:45:33,693 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) nvcsw: 17
2025-11-01 16:45:33,693 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) oublock: 8
2025-11-01 16:45:33,693 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) stime: 0.22086799999999998
2025-11-01 16:45:33,693 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) utime: 0.114369
2025-11-01 16:45:33,693 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) wtime: 0.446
2025-11-01 16:45:33,694 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:45:33,694 - DEBUG - ya.test - _save_outputs: Command (pid 3042806) errors:

2025-11-01 16:45:33,694 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 7 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:45:35,703 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:35,711 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:45:35,711 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:35,721 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_6
2025-11-01 16:45:35,722 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, dabd12b8-5049-46ec-88e2-3934b3ec0a64, localhost:65199): creating call state
2025-11-01 16:45:35,722 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, dabd12b8-5049-46ec-88e2-3934b3ec0a64, localhost:65199): request = { database: "/Root/users/database_6" }
2025-11-01 16:45:35,725 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, dabd12b8-5049-46ec-88e2-3934b3ec0a64, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 29328 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: 50006 } self_location: "1" } } } }
2025-11-01 16:45:35,726 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:45:35,726 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_6: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:29328, location , ssl: False>]>
2025-11-01 16:45:35,727 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5dcb510f0 state=pending>
2025-11-01 16:45:35,728 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:35,729 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:45:35,731 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,732 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,732 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:45:35,740 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:35,740 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,741 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7e79cfa4-a03b-48b0-94cd-de5a439f36ce, ghrun-tvlw7frnpa.auto.internal:29328): creating call state
2025-11-01 16:45:35,741 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7e79cfa4-a03b-48b0-94cd-de5a439f36ce, ghrun-tvlw7frnpa.auto.internal:29328): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:35,744 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7e79cfa4-a03b-48b0-94cd-de5a439f36ce, ghrun-tvlw7frnpa.auto.internal:29328): on response callback started
2025-11-01 16:45:35,745 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7e79cfa4-a03b-48b0-94cd-de5a439f36ce, ghrun-tvlw7frnpa.auto.internal:29328): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50006&id=ODJkMDQ4MDktYzJjYjY5MDEtN2NiMGZhYWYtYTc1NmY1OTI=" } } } }
2025-11-01 16:45:35,745 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,746 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,747 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 9bed49f1-b8dd-49c8-a8dd-2458b1d9de40, ghrun-tvlw7frnpa.auto.internal:29328): creating call state
2025-11-01 16:45:35,747 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7e79cfa4-a03b-48b0-94cd-de5a439f36ce, ghrun-tvlw7frnpa.auto.internal:29328): on response callback success
2025-11-01 16:45:35,748 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 9bed49f1-b8dd-49c8-a8dd-2458b1d9de40, ghrun-tvlw7frnpa.auto.internal:29328): request = { session_id: "ydb://session/3?node_id=50006&id=ODJkMDQ4MDktYzJjYjY5MDEtN2NiMGZhYWYtYTc1NmY1OTI=" path: "/Root/users/database_6/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:35,805 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 9bed49f1-b8dd-49c8-a8dd-2458b1d9de40, ghrun-tvlw7frnpa.auto.internal:29328): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:35,805 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,806 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, f31c8dc3-f159-462c-9cdc-19ae96833d61, ghrun-tvlw7frnpa.auto.internal:29328): creating call state
2025-11-01 16:45:35,807 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, f31c8dc3-f159-462c-9cdc-19ae96833d61, ghrun-tvlw7frnpa.auto.internal:29328): request = { session_id: "ydb://session/3?node_id=50006&id=ODJkMDQ4MDktYzJjYjY5MDEtN2NiMGZhYWYtYTc1NmY1OTI=" path: "/Root/users/database_6/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:35,874 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, f31c8dc3-f159-462c-9cdc-19ae96833d61, ghrun-tvlw7frnpa.auto.internal:29328): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:35,874 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 074842e6-aae8-4dab-b55e-25b78dc9722f, ghrun-tvlw7frnpa.auto.internal:29328): creating call state
2025-11-01 16:45:35,884 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 074842e6-aae8-4dab-b55e-25b78dc9722f, ghrun-tvlw7frnpa.auto.internal:29328): request = { session_id: "ydb://session/3?node_id=50006&id=ODJkMDQ4MDktYzJjYjY5MDEtN2NiMGZhYWYtYTc1NmY1OTI=" 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_6\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_6\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:35,912 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 074842e6-aae8-4dab-b55e-25b78dc9722f, ghrun-tvlw7frnpa.auto.internal:29328): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:45:35,913 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,914 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 2dc7865f-f011-4092-8d62-d6cc5a000134, ghrun-tvlw7frnpa.auto.internal:29328): creating call state
2025-11-01 16:45:35,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 2dc7865f-f011-4092-8d62-d6cc5a000134, ghrun-tvlw7frnpa.auto.internal:29328): request = { session_id: "ydb://session/3?node_id=50006&id=ODJkMDQ4MDktYzJjYjY5MDEtN2NiMGZhYWYtYTc1NmY1OTI=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:35,958 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 2dc7865f-f011-4092-8d62-d6cc5a000134, ghrun-tvlw7frnpa.auto.internal:29328): 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_6" } } 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_6" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:45:35,961 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:35,967 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, f9649697-0013-4034-99b4-d96231264594, ghrun-tvlw7frnpa.auto.internal:29328): creating call state
2025-11-01 16:45:35,969 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, f9649697-0013-4034-99b4-d96231264594, ghrun-tvlw7frnpa.auto.internal:29328): request = { session_id: "ydb://session/3?node_id=50006&id=ODJkMDQ4MDktYzJjYjY5MDEtN2NiMGZhYWYtYTc1NmY1OTI=" path: "/Root/users/database_6/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:36,023 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, f9649697-0013-4034-99b4-d96231264594, ghrun-tvlw7frnpa.auto.internal:29328): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:36,027 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:36,027 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:45:36,027 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:45:36,027 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:45:36,029 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:45:36,033 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5dcb509c0>, reason: session-pool-terminated
2025-11-01 16:45:36,033 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5dcb509c0> is no longer active. Current active count 0.
2025-11-01 16:45:36,034 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a87774f5-9fa6-4343-b044-e98f0cb32f03, ghrun-tvlw7frnpa.auto.internal:29328): creating call state
2025-11-01 16:45:36,034 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a87774f5-9fa6-4343-b044-e98f0cb32f03, ghrun-tvlw7frnpa.auto.internal:29328): request = { session_id: "ydb://session/3?node_id=50006&id=ODJkMDQ4MDktYzJjYjY5MDEtN2NiMGZhYWYtYTc1NmY1OTI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:36,036 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5dcb509c0>
2025-11-01 16:45:36,036 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:45:36,036 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:45:36,037 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:45:36,037 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, a87774f5-9fa6-4343-b044-e98f0cb32f03, ghrun-tvlw7frnpa.auto.internal:29328): on response callback started
2025-11-01 16:45:36,037 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:36,038 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:29328
2025-11-01 16:45:36,038 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a87774f5-9fa6-4343-b044-e98f0cb32f03, ghrun-tvlw7frnpa.auto.internal:29328): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:36,040 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, a87774f5-9fa6-4343-b044-e98f0cb32f03, ghrun-tvlw7frnpa.auto.internal:29328): on response callback success
2025-11-01 16:45:36,039 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:45:36,041 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_6
2025-11-01 16:45:36,042 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_6: send console operation, token None
2025-11-01 16:45:36,047 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_6: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_6&cmstid=72057594037936131&txid=1762015536044595&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:45:36,047 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_6: wait console operation done
2025-11-01 16:45:36,551 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_6: console operation done
2025-11-01 16:45:36,552 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_6: wait tenant gone
2025-11-01 16:45:36,555 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_6: tenant gone
2025-11-01 16:45:37,558 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:29328/7
2025-11-01 16:45:38,078 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd
2025-11-01 16:45:38,087 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7193
2025-11-01 16:45:38,092 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15077
2025-11-01 16:45:38,093 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15679
2025-11-01 16:45:38,095 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15679
2025-11-01 16:45:38,096 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15680
2025-11-01 16:45:38,102 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26680
2025-11-01 16:45:38,103 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63442
2025-11-01 16:45:38,125 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:38,126 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_7 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_8/logfile_dy8w88zy.log --grpc-port=7193 --mon-port=15077 --ic-port=15680
2025-11-01 16:45:38,126 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 16:45:38,126 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:65199 --tenant=/Root/users/database_7 --log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_8/logfile_dy8w88zy.log --grpc-port=7193 --mon-port=15077 --ic-port=15680
2025-11-01 16:45:38,130 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:65199', '--tenant=/Root/users/database_7', '--log-file-name=/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_8/logfile_dy8w88zy.log', '--grpc-port=7193', '--mon-port=15077', '--ic-port=15680')' in '/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_8' (no wait)
2025-11-01 16:45:38,136 - DEBUG - ya.test - execute: Command pid: 3043954
2025-11-01 16:45:38,137 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:7193/8
2025-11-01 16:45:38,139 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.26
2025-11-01 16:45:38,151 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.26
2025-11-01 16:45:38,152 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/q6a6/0023f4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:45:38,161 - DEBUG - ya.test - execute: Command pid: 3043956
2025-11-01 16:45:38,655 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) rc: 0
2025-11-01 16:45:38,656 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) elapsed time (sec): 0.4721689224243164
2025-11-01 16:45:38,656 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) idrss: 0
2025-11-01 16:45:38,656 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) inblock: 0
2025-11-01 16:45:38,656 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) isrss: 0
2025-11-01 16:45:38,656 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) ixrss: 0
2025-11-01 16:45:38,656 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) majflt: 0
2025-11-01 16:45:38,657 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) maxrss: 538512
2025-11-01 16:45:38,657 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) minflt: 16129
2025-11-01 16:45:38,658 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) msgrcv: 0
2025-11-01 16:45:38,658 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) msgsnd: 0
2025-11-01 16:45:38,659 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) nivcsw: 107
2025-11-01 16:45:38,659 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) nsignals: 0
2025-11-01 16:45:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) nswap: 0
2025-11-01 16:45:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) nvcsw: 13
2025-11-01 16:45:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) oublock: 8
2025-11-01 16:45:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) stime: 0.25757399999999997
2025-11-01 16:45:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) utime: 0.117667
2025-11-01 16:45:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) wtime: 0.503
2025-11-01 16:45:38,662 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tvlw7frnpa
    Host information: 
        Linux ghrun-tvlw7frnpa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-01 16:45:38,662 - DEBUG - ya.test - _save_outputs: Command (pid 3043956) errors:

2025-11-01 16:45:38,662 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 8 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:45:40,673 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:40,678 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:45:40,687 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:40,694 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_7
2025-11-01 16:45:40,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3b493562-8243-4a1e-b82f-a7a7e398e264, localhost:65199): creating call state
2025-11-01 16:45:40,699 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 16:45:40,700 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3b493562-8243-4a1e-b82f-a7a7e398e264, localhost:65199): request = { database: "/Root/users/database_7" }
2025-11-01 16:45:40,701 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7fb5f12a8d80 state=pending>
2025-11-01 16:45:40,703 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 16:45:40,703 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,707 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,708 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 16:45:40,704 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3b493562-8243-4a1e-b82f-a7a7e398e264, localhost:65199): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tvlw7frnpa.auto.internal" port: 7193 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: 50007 } self_location: "1" } } } }
2025-11-01 16:45:40,709 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_7: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tvlw7frnpa.auto.internal:7193, location , ssl: False>]>
2025-11-01 16:45:40,710 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:45:40,718 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:40,718 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,724 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7b012dd5-1cce-4d5d-9d9d-c998ab5a17b7, ghrun-tvlw7frnpa.auto.internal:7193): creating call state
2025-11-01 16:45:40,726 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7b012dd5-1cce-4d5d-9d9d-c998ab5a17b7, ghrun-tvlw7frnpa.auto.internal:7193): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:40,733 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7b012dd5-1cce-4d5d-9d9d-c998ab5a17b7, ghrun-tvlw7frnpa.auto.internal:7193): on response callback started
2025-11-01 16:45:40,734 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7b012dd5-1cce-4d5d-9d9d-c998ab5a17b7, ghrun-tvlw7frnpa.auto.internal:7193): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50007&id=ZDU5YTE4MDgtMjcxMmVkODItNTBiYWY4YzEtY2VjZTA4OTk=" } } } }
2025-11-01 16:45:40,734 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,734 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,735 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7b012dd5-1cce-4d5d-9d9d-c998ab5a17b7, ghrun-tvlw7frnpa.auto.internal:7193): on response callback success
2025-11-01 16:45:40,736 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 04de173b-a150-4307-ae91-498429a4ff4e, ghrun-tvlw7frnpa.auto.internal:7193): creating call state
2025-11-01 16:45:40,739 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 04de173b-a150-4307-ae91-498429a4ff4e, ghrun-tvlw7frnpa.auto.internal:7193): request = { session_id: "ydb://session/3?node_id=50007&id=ZDU5YTE4MDgtMjcxMmVkODItNTBiYWY4YzEtY2VjZTA4OTk=" path: "/Root/users/database_7/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:40,799 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 04de173b-a150-4307-ae91-498429a4ff4e, ghrun-tvlw7frnpa.auto.internal:7193): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:40,799 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,809 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 676c6a1f-61ce-44b9-a01f-55b521061ab4, ghrun-tvlw7frnpa.auto.internal:7193): creating call state
2025-11-01 16:45:40,810 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 676c6a1f-61ce-44b9-a01f-55b521061ab4, ghrun-tvlw7frnpa.auto.internal:7193): request = { session_id: "ydb://session/3?node_id=50007&id=ZDU5YTE4MDgtMjcxMmVkODItNTBiYWY4YzEtY2VjZTA4OTk=" path: "/Root/users/database_7/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:40,855 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 676c6a1f-61ce-44b9-a01f-55b521061ab4, ghrun-tvlw7frnpa.auto.internal:7193): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:40,855 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,856 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7d9962d9-03c7-4466-837c-ca29035a9f38, ghrun-tvlw7frnpa.auto.internal:7193): creating call state
2025-11-01 16:45:40,857 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7d9962d9-03c7-4466-837c-ca29035a9f38, ghrun-tvlw7frnpa.auto.internal:7193): request = { session_id: "ydb://session/3?node_id=50007&id=ZDU5YTE4MDgtMjcxMmVkODItNTBiYWY4YzEtY2VjZTA4OTk=" 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_7\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_7\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:40,887 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7d9962d9-03c7-4466-837c-ca29035a9f38, ghrun-tvlw7frnpa.auto.internal:7193): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 16:45:40,891 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,892 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 53ca6740-e43b-44e3-a3e0-bec0d4a4e5ce, ghrun-tvlw7frnpa.auto.internal:7193): creating call state
2025-11-01 16:45:40,897 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 53ca6740-e43b-44e3-a3e0-bec0d4a4e5ce, ghrun-tvlw7frnpa.auto.internal:7193): request = { session_id: "ydb://session/3?node_id=50007&id=ZDU5YTE4MDgtMjcxMmVkODItNTBiYWY4YzEtY2VjZTA4OTk=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:40,942 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 53ca6740-e43b-44e3-a3e0-bec0d4a4e5ce, ghrun-tvlw7frnpa.auto.internal:7193): 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_7" } } 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_7" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-11-01 16:45:40,943 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:40,944 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 1a872374-4c55-48a0-9130-532b0c6f8d3b, ghrun-tvlw7frnpa.auto.internal:7193): creating call state
2025-11-01 16:45:40,944 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 1a872374-4c55-48a0-9130-532b0c6f8d3b, ghrun-tvlw7frnpa.auto.internal:7193): request = { session_id: "ydb://session/3?node_id=50007&id=ZDU5YTE4MDgtMjcxMmVkODItNTBiYWY4YzEtY2VjZTA4OTk=" path: "/Root/users/database_7/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 16:45:40,969 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 1a872374-4c55-48a0-9130-532b0c6f8d3b, ghrun-tvlw7frnpa.auto.internal:7193): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:41,013 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:41,014 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 16:45:41,014 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 16:45:41,014 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 16:45:41,039 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 16:45:41,055 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fb5f12a8c90>, reason: session-pool-terminated
2025-11-01 16:45:41,055 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7fb5f12a8c90> is no longer active. Current active count 0.
2025-11-01 16:45:41,056 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ef9bfbdb-3b0a-4520-8f61-604417bc3d5d, ghrun-tvlw7frnpa.auto.internal:7193): creating call state
2025-11-01 16:45:41,063 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ef9bfbdb-3b0a-4520-8f61-604417bc3d5d, ghrun-tvlw7frnpa.auto.internal:7193): request = { session_id: "ydb://session/3?node_id=50007&id=ZDU5YTE4MDgtMjcxMmVkODItNTBiYWY4YzEtY2VjZTA4OTk=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 16:45:41,064 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7fb5f12a8c90>
2025-11-01 16:45:41,065 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 16:45:41,066 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 16:45:41,066 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 16:45:41,066 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:65199
2025-11-01 16:45:41,067 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-tvlw7frnpa.auto.internal:7193
2025-11-01 16:45:41,068 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ef9bfbdb-3b0a-4520-8f61-604417bc3d5d, ghrun-tvlw7frnpa.auto.internal:7193): on response callback started
2025-11-01 16:45:41,069 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 16:45:41,073 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ef9bfbdb-3b0a-4520-8f61-604417bc3d5d, ghrun-tvlw7frnpa.auto.internal:7193): response = { operation { ready: true status: SUCCESS } }
2025-11-01 16:45:41,074 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ef9bfbdb-3b0a-4520-8f61-604417bc3d5d, ghrun-tvlw7frnpa.auto.internal:7193): on response callback success
2025-11-01 16:45:41,074 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_7
2025-11-01 16:45:41,076 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_7: send console operation, token None
2025-11-01 16:45:41,084 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_7: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_7&cmstid=72057594037936131&txid=1762015541079809&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 16:45:41,088 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_7: wait console operation done
2025-11-01 16:45:41,593 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_7: console operation done
2025-11-01 16:45:41,593 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_7: wait tenant gone
2025-11-01 16:45:41,598 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_7: tenant gone
2025-11-01 16:45:44,602 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:7193/8
2025-11-01 16:45:45,201 - ERROR - ya.test - logreport: ydb/tests/functional/tenants/test_dynamic_tenants.py:238: in test_create_and_drop_tenants
    ydb_cluster.create_database(
ydb/tests/library/harness/kikimr_cluster_interface.py:231: in create_database
    raise RuntimeError('create_database failed: %s, %s' % (operation.status, ydb.issues._format_issues(operation.issues)))
E   RuntimeError: create_database failed: 400080, message: "Group fit error BoxId# 1 StoragePoolId# 10 Error# failed to allocate group: no group options PDisks# {[(1:1-S)]}" severity: 1
2025-11-01 16:45:45,204 - INFO - ya.test - pytest_runtest_teardown: Test teardown
