2025-11-11 14:46:30,407 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 14:46:30,407 - INFO - ya.test - pytest_runtest_setup: test_create_and_drop_tenants[enable_alter_database_create_hive_first--true]
2025-11-11 14:46:30,407 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 14:46:30,407 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 14:46:30,408 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-11-11 14:46:37,416 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:27204/1
2025-11-11 14:46:37,474 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61993
2025-11-11 14:46:37,474 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23596
2025-11-11 14:46:37,474 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18438
2025-11-11 14:46:37,474 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16348
2025-11-11 14:46:37,474 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29952
2025-11-11 14:46:37,475 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61189
2025-11-11 14:46:37,475 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24764
2025-11-11 14:46:37,475 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4528
2025-11-11 14:46:37,475 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9110
2025-11-11 14:46:37,475 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3476
2025-11-11 14:46:37,475 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62243
2025-11-11 14:46:37,476 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28993
2025-11-11 14:46:37,476 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28627
2025-11-11 14:46:37,476 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13022
2025-11-11 14:46:37,476 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15719
2025-11-11 14:46:37,478 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19675
2025-11-11 14:46:37,478 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18924
2025-11-11 14:46:37,478 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21473
2025-11-11 14:46:37,478 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25336
2025-11-11 14:46:37,479 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11579
2025-11-11 14:46:37,479 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10075
2025-11-11 14:46:37,479 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32709
2025-11-11 14:46:37,479 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14871
2025-11-11 14:46:37,479 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8445
2025-11-11 14:46:37,479 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15005
2025-11-11 14:46:37,480 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11000
2025-11-11 14:46:37,480 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23914
2025-11-11 14:46:37,480 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24126
2025-11-11 14:46:37,480 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17886
2025-11-11 14:46:37,480 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6061
2025-11-11 14:46:37,481 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32744
2025-11-11 14:46:37,481 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18298
2025-11-11 14:46:37,481 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13808
2025-11-11 14:46:37,482 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6646
2025-11-11 14:46:37,482 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62138
2025-11-11 14:46:37,482 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23998
2025-11-11 14:46:37,482 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6617
2025-11-11 14:46:37,482 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62663
2025-11-11 14:46:37,482 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63339
2025-11-11 14:46:37,483 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5143
2025-11-11 14:46:37,483 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4129
2025-11-11 14:46:37,483 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22838
2025-11-11 14:46:37,483 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8344
2025-11-11 14:46:37,483 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61315
2025-11-11 14:46:37,483 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8677
2025-11-11 14:46:37,484 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20693
2025-11-11 14:46:37,484 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13356
2025-11-11 14:46:37,484 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20449
2025-11-11 14:46:37,484 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1343
2025-11-11 14:46:37,484 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4270
2025-11-11 14:46:37,484 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19637
2025-11-11 14:46:37,485 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29610
2025-11-11 14:46:37,485 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1058
2025-11-11 14:46:37,485 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27204
2025-11-11 14:46:37,485 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26375
2025-11-11 14:46:37,550 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:46:37,577 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22213
2025-11-11 14:46:37,578 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-11-11 14:46:37,578 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-11-11 14:46:37,579 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/bs45/002c15/r3tmp/kikimr_cluster_7hha1bwf
2025-11-11 14:46:37,625 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:46:37,625 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8880
2025-11-11 14:46:37,626 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12071
2025-11-11 14:46:37,626 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7767
2025-11-11 14:46:37,626 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7768
2025-11-11 14:46:37,627 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5901
2025-11-11 14:46:37,634 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:37,695 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/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/bs45/002c15/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_g1m3ksgx.log --grpc-port=8880 --mon-port=12071 --ic-port=22213
2025-11-11 14:46:37,695 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:8880/1, disk_size 68719476736
2025-11-11 14:46:37,695 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:37,695 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/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/bs45/002c15/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_g1m3ksgx.log --grpc-port=8880 --mon-port=12071 --ic-port=22213
2025-11-11 14:46:37,697 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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/bs45/002c15/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_g1m3ksgx.log', '--grpc-port=8880', '--mon-port=12071', '--ic-port=22213')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:37,709 - DEBUG - ya.test - execute: Command pid: 137666
2025-11-11 14:46:37,795 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:8880/1
2025-11-11 14:46:37,797 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.17
2025-11-11 14:46:37,797 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.17
2025-11-11 14:46:37,798 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:46:37,804 - DEBUG - ya.test - execute: Command pid: 137753
2025-11-11 14:46:38,658 - DEBUG - ya.test - _save_outputs: Command (pid 137753) rc: 0
2025-11-11 14:46:38,659 - DEBUG - ya.test - _save_outputs: Command (pid 137753) elapsed time (sec): 0.8000402450561523
2025-11-11 14:46:38,659 - DEBUG - ya.test - _save_outputs: Command (pid 137753) idrss: 0
2025-11-11 14:46:38,659 - DEBUG - ya.test - _save_outputs: Command (pid 137753) inblock: 0
2025-11-11 14:46:38,659 - DEBUG - ya.test - _save_outputs: Command (pid 137753) isrss: 0
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) ixrss: 0
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) majflt: 0
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) maxrss: 543264
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) minflt: 16227
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) msgrcv: 0
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) msgsnd: 0
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) nivcsw: 158
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) nsignals: 0
2025-11-11 14:46:38,660 - DEBUG - ya.test - _save_outputs: Command (pid 137753) nswap: 0
2025-11-11 14:46:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 137753) nvcsw: 13
2025-11-11 14:46:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 137753) oublock: 8
2025-11-11 14:46:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 137753) stime: 0.49546599999999996
2025-11-11 14:46:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 137753) utime: 0.111759
2025-11-11 14:46:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 137753) wtime: 0.86
2025-11-11 14:46:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 137753) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:46:38,661 - DEBUG - ya.test - _save_outputs: Command (pid 137753) errors:

2025-11-11 14:46:38,662 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:46:51,540 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:46:51,540 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8880', '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: 22213\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-11 14:46:51,542 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.18
2025-11-11 14:46:51,551 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.18
2025-11-11 14:46:51,559 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8880', '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: 22213\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-11 14:46:51,572 - DEBUG - ya.test - execute: Command pid: 146934
2025-11-11 14:46:52,636 - DEBUG - ya.test - _save_outputs: Command (pid 146934) rc: 0
2025-11-11 14:46:52,636 - DEBUG - ya.test - _save_outputs: Command (pid 146934) elapsed time (sec): 0.8663620948791504
2025-11-11 14:46:52,636 - DEBUG - ya.test - _save_outputs: Command (pid 146934) idrss: 0
2025-11-11 14:46:52,636 - DEBUG - ya.test - _save_outputs: Command (pid 146934) inblock: 0
2025-11-11 14:46:52,637 - DEBUG - ya.test - _save_outputs: Command (pid 146934) isrss: 0
2025-11-11 14:46:52,637 - DEBUG - ya.test - _save_outputs: Command (pid 146934) ixrss: 0
2025-11-11 14:46:52,637 - DEBUG - ya.test - _save_outputs: Command (pid 146934) majflt: 0
2025-11-11 14:46:52,637 - DEBUG - ya.test - _save_outputs: Command (pid 146934) maxrss: 551656
2025-11-11 14:46:52,637 - DEBUG - ya.test - _save_outputs: Command (pid 146934) minflt: 18367
2025-11-11 14:46:52,637 - DEBUG - ya.test - _save_outputs: Command (pid 146934) msgrcv: 0
2025-11-11 14:46:52,638 - DEBUG - ya.test - _save_outputs: Command (pid 146934) msgsnd: 0
2025-11-11 14:46:52,638 - DEBUG - ya.test - _save_outputs: Command (pid 146934) nivcsw: 177
2025-11-11 14:46:52,638 - DEBUG - ya.test - _save_outputs: Command (pid 146934) nsignals: 0
2025-11-11 14:46:52,638 - DEBUG - ya.test - _save_outputs: Command (pid 146934) nswap: 0
2025-11-11 14:46:52,638 - DEBUG - ya.test - _save_outputs: Command (pid 146934) nvcsw: 118
2025-11-11 14:46:52,638 - DEBUG - ya.test - _save_outputs: Command (pid 146934) oublock: 8
2025-11-11 14:46:52,638 - DEBUG - ya.test - _save_outputs: Command (pid 146934) stime: 0.46701
2025-11-11 14:46:52,639 - DEBUG - ya.test - _save_outputs: Command (pid 146934) utime: 0.114923
2025-11-11 14:46:52,639 - DEBUG - ya.test - _save_outputs: Command (pid 146934) wtime: 0.879
2025-11-11 14:46:52,639 - DEBUG - ya.test - _save_outputs: Command (pid 146934) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-11 14:46:52,639 - DEBUG - ya.test - _save_outputs: Command (pid 146934) errors:

2025-11-11 14:46:52,640 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:46:52,640 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8880', '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-11 14:46:52,641 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.19
2025-11-11 14:46:52,642 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.19
2025-11-11 14:46:52,642 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8880', '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-11 14:46:52,648 - DEBUG - ya.test - execute: Command pid: 147633
2025-11-11 14:46:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 147633) rc: 0
2025-11-11 14:46:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 147633) elapsed time (sec): 0.712188720703125
2025-11-11 14:46:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 147633) idrss: 0
2025-11-11 14:46:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 147633) inblock: 0
2025-11-11 14:46:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 147633) isrss: 0
2025-11-11 14:46:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 147633) ixrss: 0
2025-11-11 14:46:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 147633) majflt: 0
2025-11-11 14:46:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 147633) maxrss: 551652
2025-11-11 14:46:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 147633) minflt: 18367
2025-11-11 14:46:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 147633) msgrcv: 0
2025-11-11 14:46:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 147633) msgsnd: 0
2025-11-11 14:46:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 147633) nivcsw: 132
2025-11-11 14:46:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 147633) nsignals: 0
2025-11-11 14:46:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 147633) nswap: 0
2025-11-11 14:46:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 147633) nvcsw: 106
2025-11-11 14:46:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 147633) oublock: 8
2025-11-11 14:46:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 147633) stime: 0.42003199999999996
2025-11-11 14:46:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 147633) utime: 0.133646
2025-11-11 14:46:53,364 - DEBUG - ya.test - _save_outputs: Command (pid 147633) wtime: 0.718
2025-11-11 14:46:53,364 - DEBUG - ya.test - _save_outputs: Command (pid 147633) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-11 14:46:53,364 - DEBUG - ya.test - _save_outputs: Command (pid 147633) errors:

2025-11-11 14:46:53,364 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-11 14:46:53,393 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-11 14:46:53,428 - 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-11 14:46:53,948 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:46:53,984 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5738
2025-11-11 14:46:53,985 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61237
2025-11-11 14:46:53,985 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11206
2025-11-11 14:46:53,998 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19016
2025-11-11 14:46:53,999 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5512
2025-11-11 14:46:54,001 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:54,001 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_0 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_okbpulvw.log --grpc-port=5738 --mon-port=61237 --ic-port=11206
2025-11-11 14:46:54,001 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:54,001 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_0 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_okbpulvw.log --grpc-port=5738 --mon-port=61237 --ic-port=11206
2025-11-11 14:46:54,002 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_0', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_okbpulvw.log', '--grpc-port=5738', '--mon-port=61237', '--ic-port=11206')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:54,023 - DEBUG - ya.test - execute: Command pid: 148679
2025-11-11 14:46:54,098 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5738/1
2025-11-11 14:46:54,109 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.20
2025-11-11 14:46:54,110 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.20
2025-11-11 14:46:54,110 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:46:54,111 - DEBUG - ya.test - execute: Command pid: 148717
2025-11-11 14:46:54,526 - DEBUG - ya.test - _save_outputs: Command (pid 148717) rc: 0
2025-11-11 14:46:54,527 - DEBUG - ya.test - _save_outputs: Command (pid 148717) elapsed time (sec): 0.4142580032348633
2025-11-11 14:46:54,527 - DEBUG - ya.test - _save_outputs: Command (pid 148717) idrss: 0
2025-11-11 14:46:54,527 - DEBUG - ya.test - _save_outputs: Command (pid 148717) inblock: 0
2025-11-11 14:46:54,527 - DEBUG - ya.test - _save_outputs: Command (pid 148717) isrss: 0
2025-11-11 14:46:54,527 - DEBUG - ya.test - _save_outputs: Command (pid 148717) ixrss: 0
2025-11-11 14:46:54,527 - DEBUG - ya.test - _save_outputs: Command (pid 148717) majflt: 0
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) maxrss: 543264
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) minflt: 16224
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) msgrcv: 0
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) msgsnd: 0
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) nivcsw: 66
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) nsignals: 0
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) nswap: 0
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) nvcsw: 12
2025-11-11 14:46:54,528 - DEBUG - ya.test - _save_outputs: Command (pid 148717) oublock: 8
2025-11-11 14:46:54,529 - DEBUG - ya.test - _save_outputs: Command (pid 148717) stime: 0.22153799999999998
2025-11-11 14:46:54,529 - DEBUG - ya.test - _save_outputs: Command (pid 148717) utime: 0.114725
2025-11-11 14:46:54,529 - DEBUG - ya.test - _save_outputs: Command (pid 148717) wtime: 0.416
2025-11-11 14:46:54,529 - DEBUG - ya.test - _save_outputs: Command (pid 148717) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:46:54,529 - DEBUG - ya.test - _save_outputs: Command (pid 148717) errors:

2025-11-11 14:46:54,530 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:46:56,544 - 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-11 14:46:56,571 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:46:56,572 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:46:56,584 - 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-11 14:46:56,584 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f424fd0f760 state=pending>
2025-11-11 14:46:56,588 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:46:56,589 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,589 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,591 - 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-11 14:46:56,593 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_0
2025-11-11 14:46:56,593 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 957816fe-02dd-49e0-8f22-df1d9391d7b9, localhost:8880): creating call state
2025-11-11 14:46:56,594 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 957816fe-02dd-49e0-8f22-df1d9391d7b9, localhost:8880): request = { database: "/Root/users/database_0" }
2025-11-11 14:46:56,597 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 957816fe-02dd-49e0-8f22-df1d9391d7b9, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 5738 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-11 14:46:56,597 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_0: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:5738, location , ssl: False>]>
2025-11-11 14:46:56,598 - 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-11 14:46:56,612 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:46:56,612 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,636 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b7b5cdcd-d909-4f31-b46c-030d5ca2a771, ghrun-l3u76ogmey.auto.internal:5738): creating call state
2025-11-11 14:46:56,646 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b7b5cdcd-d909-4f31-b46c-030d5ca2a771, ghrun-l3u76ogmey.auto.internal:5738): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:46:56,665 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, b7b5cdcd-d909-4f31-b46c-030d5ca2a771, ghrun-l3u76ogmey.auto.internal:5738): on response callback started
2025-11-11 14:46:56,670 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b7b5cdcd-d909-4f31-b46c-030d5ca2a771, ghrun-l3u76ogmey.auto.internal:5738): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YTQ2MDI0MTEtMjk2MTRkMDQtMzcwNGVmMWUtM2Y1MTUyZGE=" } } } }
2025-11-11 14:46:56,679 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,679 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,680 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, cf1b45ff-51de-40cc-8926-e3f77cf6a79a, ghrun-l3u76ogmey.auto.internal:5738): creating call state
2025-11-11 14:46:56,680 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, b7b5cdcd-d909-4f31-b46c-030d5ca2a771, ghrun-l3u76ogmey.auto.internal:5738): on response callback success
2025-11-11 14:46:56,681 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, cf1b45ff-51de-40cc-8926-e3f77cf6a79a, ghrun-l3u76ogmey.auto.internal:5738): request = { session_id: "ydb://session/3?node_id=50000&id=YTQ2MDI0MTEtMjk2MTRkMDQtMzcwNGVmMWUtM2Y1MTUyZGE=" 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-11 14:46:56,736 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, cf1b45ff-51de-40cc-8926-e3f77cf6a79a, ghrun-l3u76ogmey.auto.internal:5738): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:46:56,737 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,737 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, e939c8a1-0c15-44e2-8a97-36795cad8129, ghrun-l3u76ogmey.auto.internal:5738): creating call state
2025-11-11 14:46:56,738 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, e939c8a1-0c15-44e2-8a97-36795cad8129, ghrun-l3u76ogmey.auto.internal:5738): request = { session_id: "ydb://session/3?node_id=50000&id=YTQ2MDI0MTEtMjk2MTRkMDQtMzcwNGVmMWUtM2Y1MTUyZGE=" 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-11 14:46:56,768 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, e939c8a1-0c15-44e2-8a97-36795cad8129, ghrun-l3u76ogmey.auto.internal:5738): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:46:56,768 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,769 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 86e295eb-8279-46be-9ce5-35db676a8666, ghrun-l3u76ogmey.auto.internal:5738): creating call state
2025-11-11 14:46:56,770 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 86e295eb-8279-46be-9ce5-35db676a8666, ghrun-l3u76ogmey.auto.internal:5738): request = { session_id: "ydb://session/3?node_id=50000&id=YTQ2MDI0MTEtMjk2MTRkMDQtMzcwNGVmMWUtM2Y1MTUyZGE=" 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-11 14:46:56,805 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 86e295eb-8279-46be-9ce5-35db676a8666, ghrun-l3u76ogmey.auto.internal:5738): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:46:56,807 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,807 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 90cdb9c7-d808-4f4e-b52b-8b9e31f1ac0e, ghrun-l3u76ogmey.auto.internal:5738): creating call state
2025-11-11 14:46:56,808 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 90cdb9c7-d808-4f4e-b52b-8b9e31f1ac0e, ghrun-l3u76ogmey.auto.internal:5738): request = { session_id: "ydb://session/3?node_id=50000&id=YTQ2MDI0MTEtMjk2MTRkMDQtMzcwNGVmMWUtM2Y1MTUyZGE=" 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-11 14:46:56,853 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 90cdb9c7-d808-4f4e-b52b-8b9e31f1ac0e, ghrun-l3u76ogmey.auto.internal:5738): 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-11 14:46:56,853 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,854 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 0426d7db-d2b5-4a4f-a168-e7438824e842, ghrun-l3u76ogmey.auto.internal:5738): creating call state
2025-11-11 14:46:56,854 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 0426d7db-d2b5-4a4f-a168-e7438824e842, ghrun-l3u76ogmey.auto.internal:5738): request = { session_id: "ydb://session/3?node_id=50000&id=YTQ2MDI0MTEtMjk2MTRkMDQtMzcwNGVmMWUtM2Y1MTUyZGE=" path: "/Root/users/database_0/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:46:56,885 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 0426d7db-d2b5-4a4f-a168-e7438824e842, ghrun-l3u76ogmey.auto.internal:5738): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:46:56,886 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,886 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:46:56,886 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:46:56,886 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:46:56,887 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f424fd0d460>, reason: session-pool-terminated
2025-11-11 14:46:56,887 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f424fd0d460> is no longer active. Current active count 0.
2025-11-11 14:46:56,887 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 2f49f619-d2ca-4bb5-8659-9182c66739f7, ghrun-l3u76ogmey.auto.internal:5738): creating call state
2025-11-11 14:46:56,893 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 2f49f619-d2ca-4bb5-8659-9182c66739f7, ghrun-l3u76ogmey.auto.internal:5738): request = { session_id: "ydb://session/3?node_id=50000&id=YTQ2MDI0MTEtMjk2MTRkMDQtMzcwNGVmMWUtM2Y1MTUyZGE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:46:56,917 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f424fd0d460>
2025-11-11 14:46:56,918 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:46:56,918 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 2f49f619-d2ca-4bb5-8659-9182c66739f7, ghrun-l3u76ogmey.auto.internal:5738): on response callback started
2025-11-11 14:46:56,919 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 2f49f619-d2ca-4bb5-8659-9182c66739f7, ghrun-l3u76ogmey.auto.internal:5738): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:46:56,919 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 2f49f619-d2ca-4bb5-8659-9182c66739f7, ghrun-l3u76ogmey.auto.internal:5738): on response callback success
2025-11-11 14:46:56,896 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:46:56,920 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:46:56,920 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:46:56,921 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:46:56,924 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:5738
2025-11-11 14:46:56,924 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:46:56,925 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_0
2025-11-11 14:46:56,925 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_0: send console operation, token None
2025-11-11 14:46:56,930 - 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=1762872416928332&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:46:56,950 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_0: wait console operation done
2025-11-11 14:46:57,470 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_0: console operation done
2025-11-11 14:46:57,485 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_0: wait tenant gone
2025-11-11 14:46:57,489 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_0: tenant gone
2025-11-11 14:46:58,490 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5738/1
2025-11-11 14:46:59,009 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:46:59,075 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10162
2025-11-11 14:46:59,075 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29049
2025-11-11 14:46:59,076 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29628
2025-11-11 14:46:59,076 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28049
2025-11-11 14:46:59,106 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7606
2025-11-11 14:46:59,107 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:59,107 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_1 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_tbocr2o1.log --grpc-port=10162 --mon-port=29049 --ic-port=29628
2025-11-11 14:46:59,108 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:59,108 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_1 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_tbocr2o1.log --grpc-port=10162 --mon-port=29049 --ic-port=29628
2025-11-11 14:46:59,109 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_1', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_tbocr2o1.log', '--grpc-port=10162', '--mon-port=29049', '--ic-port=29628')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:46:59,112 - DEBUG - ya.test - execute: Command pid: 152503
2025-11-11 14:46:59,113 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10162/2
2025-11-11 14:46:59,114 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.21
2025-11-11 14:46:59,115 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.21
2025-11-11 14:46:59,116 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:46:59,119 - DEBUG - ya.test - execute: Command pid: 152520
2025-11-11 14:46:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 152520) rc: 0
2025-11-11 14:46:59,532 - DEBUG - ya.test - _save_outputs: Command (pid 152520) elapsed time (sec): 0.4125514030456543
2025-11-11 14:46:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 152520) idrss: 0
2025-11-11 14:46:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 152520) inblock: 0
2025-11-11 14:46:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 152520) isrss: 0
2025-11-11 14:46:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 152520) ixrss: 0
2025-11-11 14:46:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 152520) majflt: 0
2025-11-11 14:46:59,533 - DEBUG - ya.test - _save_outputs: Command (pid 152520) maxrss: 543264
2025-11-11 14:46:59,534 - DEBUG - ya.test - _save_outputs: Command (pid 152520) minflt: 16225
2025-11-11 14:46:59,534 - DEBUG - ya.test - _save_outputs: Command (pid 152520) msgrcv: 0
2025-11-11 14:46:59,534 - DEBUG - ya.test - _save_outputs: Command (pid 152520) msgsnd: 0
2025-11-11 14:46:59,534 - DEBUG - ya.test - _save_outputs: Command (pid 152520) nivcsw: 34
2025-11-11 14:46:59,534 - DEBUG - ya.test - _save_outputs: Command (pid 152520) nsignals: 0
2025-11-11 14:46:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 152520) nswap: 0
2025-11-11 14:46:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 152520) nvcsw: 18
2025-11-11 14:46:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 152520) oublock: 8
2025-11-11 14:46:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 152520) stime: 0.203611
2025-11-11 14:46:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 152520) utime: 0.11187899999999999
2025-11-11 14:46:59,535 - DEBUG - ya.test - _save_outputs: Command (pid 152520) wtime: 0.416
2025-11-11 14:46:59,536 - DEBUG - ya.test - _save_outputs: Command (pid 152520) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:46:59,536 - DEBUG - ya.test - _save_outputs: Command (pid 152520) errors:

2025-11-11 14:46:59,536 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:47:01,543 - 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-11 14:47:01,552 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:47:01,556 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:47:01,560 - 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-11 14:47:01,561 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4253745e10 state=pending>
2025-11-11 14:47:01,565 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:47:01,566 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,567 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,568 - 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-11 14:47:01,569 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_1
2025-11-11 14:47:01,569 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 1b26150a-50eb-47a4-b417-5ac3175b7b67, localhost:8880): creating call state
2025-11-11 14:47:01,570 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 1b26150a-50eb-47a4-b417-5ac3175b7b67, localhost:8880): request = { database: "/Root/users/database_1" }
2025-11-11 14:47:01,573 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 1b26150a-50eb-47a4-b417-5ac3175b7b67, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 10162 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-11 14:47:01,573 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_1: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:10162, location , ssl: False>]>
2025-11-11 14:47:01,574 - 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-11 14:47:01,586 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:01,586 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,587 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 559a00d5-1543-46ea-bdf3-50fecf7f4c8d, ghrun-l3u76ogmey.auto.internal:10162): creating call state
2025-11-11 14:47:01,588 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 559a00d5-1543-46ea-bdf3-50fecf7f4c8d, ghrun-l3u76ogmey.auto.internal:10162): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:01,591 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 559a00d5-1543-46ea-bdf3-50fecf7f4c8d, ghrun-l3u76ogmey.auto.internal:10162): on response callback started
2025-11-11 14:47:01,591 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 559a00d5-1543-46ea-bdf3-50fecf7f4c8d, ghrun-l3u76ogmey.auto.internal:10162): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=MWJhNzEyMzYtZjljY2ZjYjYtZjhjNTg3YmMtZWEzNGE1MTc=" } } } }
2025-11-11 14:47:01,592 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,592 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,592 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 559a00d5-1543-46ea-bdf3-50fecf7f4c8d, ghrun-l3u76ogmey.auto.internal:10162): on response callback success
2025-11-11 14:47:01,593 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, b0186989-8651-4fba-a29a-0a51ed2bd320, ghrun-l3u76ogmey.auto.internal:10162): creating call state
2025-11-11 14:47:01,594 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, b0186989-8651-4fba-a29a-0a51ed2bd320, ghrun-l3u76ogmey.auto.internal:10162): request = { session_id: "ydb://session/3?node_id=50001&id=MWJhNzEyMzYtZjljY2ZjYjYtZjhjNTg3YmMtZWEzNGE1MTc=" 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-11 14:47:01,672 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, b0186989-8651-4fba-a29a-0a51ed2bd320, ghrun-l3u76ogmey.auto.internal:10162): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:01,672 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,673 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 25757c36-1308-4757-b371-e746dfcec2dd, ghrun-l3u76ogmey.auto.internal:10162): creating call state
2025-11-11 14:47:01,674 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 25757c36-1308-4757-b371-e746dfcec2dd, ghrun-l3u76ogmey.auto.internal:10162): request = { session_id: "ydb://session/3?node_id=50001&id=MWJhNzEyMzYtZjljY2ZjYjYtZjhjNTg3YmMtZWEzNGE1MTc=" 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-11 14:47:01,781 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 25757c36-1308-4757-b371-e746dfcec2dd, ghrun-l3u76ogmey.auto.internal:10162): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:01,782 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,782 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 3e20968c-96c3-4ee1-b449-436d05cf48f6, ghrun-l3u76ogmey.auto.internal:10162): creating call state
2025-11-11 14:47:01,783 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 3e20968c-96c3-4ee1-b449-436d05cf48f6, ghrun-l3u76ogmey.auto.internal:10162): request = { session_id: "ydb://session/3?node_id=50001&id=MWJhNzEyMzYtZjljY2ZjYjYtZjhjNTg3YmMtZWEzNGE1MTc=" 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-11 14:47:01,814 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 3e20968c-96c3-4ee1-b449-436d05cf48f6, ghrun-l3u76ogmey.auto.internal:10162): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:47:01,814 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,815 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, af0b110c-8224-4866-a7ad-0ae60288f8ac, ghrun-l3u76ogmey.auto.internal:10162): creating call state
2025-11-11 14:47:01,815 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, af0b110c-8224-4866-a7ad-0ae60288f8ac, ghrun-l3u76ogmey.auto.internal:10162): request = { session_id: "ydb://session/3?node_id=50001&id=MWJhNzEyMzYtZjljY2ZjYjYtZjhjNTg3YmMtZWEzNGE1MTc=" 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-11 14:47:01,854 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, af0b110c-8224-4866-a7ad-0ae60288f8ac, ghrun-l3u76ogmey.auto.internal:10162): 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-11 14:47:01,854 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,855 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, cf0426e4-f6c3-4d83-a0ac-3769a1793c97, ghrun-l3u76ogmey.auto.internal:10162): creating call state
2025-11-11 14:47:01,855 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, cf0426e4-f6c3-4d83-a0ac-3769a1793c97, ghrun-l3u76ogmey.auto.internal:10162): request = { session_id: "ydb://session/3?node_id=50001&id=MWJhNzEyMzYtZjljY2ZjYjYtZjhjNTg3YmMtZWEzNGE1MTc=" path: "/Root/users/database_1/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:47:01,879 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, cf0426e4-f6c3-4d83-a0ac-3769a1793c97, ghrun-l3u76ogmey.auto.internal:10162): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:01,879 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,880 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:47:01,880 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:47:01,880 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:47:01,880 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4253744a10>, reason: session-pool-terminated
2025-11-11 14:47:01,880 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4253744a10> is no longer active. Current active count 0.
2025-11-11 14:47:01,881 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ecfc4fae-4860-4a67-806f-afc296079400, ghrun-l3u76ogmey.auto.internal:10162): creating call state
2025-11-11 14:47:01,881 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ecfc4fae-4860-4a67-806f-afc296079400, ghrun-l3u76ogmey.auto.internal:10162): request = { session_id: "ydb://session/3?node_id=50001&id=MWJhNzEyMzYtZjljY2ZjYjYtZjhjNTg3YmMtZWEzNGE1MTc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:01,882 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:47:01,883 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4253744a10>
2025-11-11 14:47:01,883 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ecfc4fae-4860-4a67-806f-afc296079400, ghrun-l3u76ogmey.auto.internal:10162): on response callback started
2025-11-11 14:47:01,883 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:47:01,884 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ecfc4fae-4860-4a67-806f-afc296079400, ghrun-l3u76ogmey.auto.internal:10162): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:01,885 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:47:01,886 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:47:01,886 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:01,886 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:10162
2025-11-11 14:47:01,887 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:47:01,888 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_1
2025-11-11 14:47:01,888 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_1: send console operation, token None
2025-11-11 14:47:01,886 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ecfc4fae-4860-4a67-806f-afc296079400, ghrun-l3u76ogmey.auto.internal:10162): on response callback success
2025-11-11 14:47:01,895 - 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=1762872421890660&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:47:01,895 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_1: wait console operation done
2025-11-11 14:47:02,399 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_1: console operation done
2025-11-11 14:47:02,399 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_1: wait tenant gone
2025-11-11 14:47:02,403 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_1: tenant gone
2025-11-11 14:47:03,404 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10162/2
2025-11-11 14:47:03,972 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:47:03,972 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6685
2025-11-11 14:47:03,973 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27610
2025-11-11 14:47:03,973 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9278
2025-11-11 14:47:03,973 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7763
2025-11-11 14:47:03,974 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27449
2025-11-11 14:47:04,014 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:04,014 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_2 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_o840ltmu.log --grpc-port=6685 --mon-port=27610 --ic-port=9278
2025-11-11 14:47:04,014 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:04,015 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_2 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_o840ltmu.log --grpc-port=6685 --mon-port=27610 --ic-port=9278
2025-11-11 14:47:04,015 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_2', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_o840ltmu.log', '--grpc-port=6685', '--mon-port=27610', '--ic-port=9278')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:04,017 - DEBUG - ya.test - execute: Command pid: 155428
2025-11-11 14:47:04,018 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:6685/3
2025-11-11 14:47:04,019 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.22
2025-11-11 14:47:04,020 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.22
2025-11-11 14:47:04,020 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:47:04,026 - DEBUG - ya.test - execute: Command pid: 155455
2025-11-11 14:47:04,390 - DEBUG - ya.test - _save_outputs: Command (pid 155455) rc: 0
2025-11-11 14:47:04,391 - DEBUG - ya.test - _save_outputs: Command (pid 155455) elapsed time (sec): 0.2979927062988281
2025-11-11 14:47:04,391 - DEBUG - ya.test - _save_outputs: Command (pid 155455) idrss: 0
2025-11-11 14:47:04,391 - DEBUG - ya.test - _save_outputs: Command (pid 155455) inblock: 0
2025-11-11 14:47:04,391 - DEBUG - ya.test - _save_outputs: Command (pid 155455) isrss: 0
2025-11-11 14:47:04,391 - DEBUG - ya.test - _save_outputs: Command (pid 155455) ixrss: 0
2025-11-11 14:47:04,391 - DEBUG - ya.test - _save_outputs: Command (pid 155455) majflt: 0
2025-11-11 14:47:04,391 - DEBUG - ya.test - _save_outputs: Command (pid 155455) maxrss: 543264
2025-11-11 14:47:04,392 - DEBUG - ya.test - _save_outputs: Command (pid 155455) minflt: 16375
2025-11-11 14:47:04,392 - DEBUG - ya.test - _save_outputs: Command (pid 155455) msgrcv: 0
2025-11-11 14:47:04,392 - DEBUG - ya.test - _save_outputs: Command (pid 155455) msgsnd: 0
2025-11-11 14:47:04,392 - DEBUG - ya.test - _save_outputs: Command (pid 155455) nivcsw: 27
2025-11-11 14:47:04,392 - DEBUG - ya.test - _save_outputs: Command (pid 155455) nsignals: 0
2025-11-11 14:47:04,392 - DEBUG - ya.test - _save_outputs: Command (pid 155455) nswap: 0
2025-11-11 14:47:04,393 - DEBUG - ya.test - _save_outputs: Command (pid 155455) nvcsw: 13
2025-11-11 14:47:04,393 - DEBUG - ya.test - _save_outputs: Command (pid 155455) oublock: 8
2025-11-11 14:47:04,393 - DEBUG - ya.test - _save_outputs: Command (pid 155455) stime: 0.188136
2025-11-11 14:47:04,393 - DEBUG - ya.test - _save_outputs: Command (pid 155455) utime: 0.112081
2025-11-11 14:47:04,393 - DEBUG - ya.test - _save_outputs: Command (pid 155455) wtime: 0.359
2025-11-11 14:47:04,393 - DEBUG - ya.test - _save_outputs: Command (pid 155455) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:47:04,393 - DEBUG - ya.test - _save_outputs: Command (pid 155455) errors:

2025-11-11 14:47:04,394 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 3 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:47:06,403 - 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-11 14:47:06,405 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:47:06,406 - 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-11 14:47:06,407 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:47:06,411 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f42514b38a0 state=pending>
2025-11-11 14:47:06,412 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:47:06,412 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,412 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,413 - 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-11 14:47:06,415 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_2
2025-11-11 14:47:06,415 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 27799057-9620-46ca-b004-a34a7ba08b62, localhost:8880): creating call state
2025-11-11 14:47:06,416 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 27799057-9620-46ca-b004-a34a7ba08b62, localhost:8880): request = { database: "/Root/users/database_2" }
2025-11-11 14:47:06,418 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 27799057-9620-46ca-b004-a34a7ba08b62, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 6685 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-11 14:47:06,419 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_2: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:6685, location , ssl: False>]>
2025-11-11 14:47:06,419 - 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-11 14:47:06,427 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:06,427 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,428 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6d1ac060-3da7-416a-bb4d-2335a19c470e, ghrun-l3u76ogmey.auto.internal:6685): creating call state
2025-11-11 14:47:06,428 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6d1ac060-3da7-416a-bb4d-2335a19c470e, ghrun-l3u76ogmey.auto.internal:6685): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:06,437 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6d1ac060-3da7-416a-bb4d-2335a19c470e, ghrun-l3u76ogmey.auto.internal:6685): on response callback started
2025-11-11 14:47:06,438 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6d1ac060-3da7-416a-bb4d-2335a19c470e, ghrun-l3u76ogmey.auto.internal:6685): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=ZTAxYTdjYjctZjExZjQ1M2MtZmFmNTNmNWYtNWYwYjNmNzg=" } } } }
2025-11-11 14:47:06,438 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,438 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,439 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 3739649a-7ca7-4696-9f04-00d980b0eb2e, ghrun-l3u76ogmey.auto.internal:6685): creating call state
2025-11-11 14:47:06,440 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6d1ac060-3da7-416a-bb4d-2335a19c470e, ghrun-l3u76ogmey.auto.internal:6685): on response callback success
2025-11-11 14:47:06,443 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 3739649a-7ca7-4696-9f04-00d980b0eb2e, ghrun-l3u76ogmey.auto.internal:6685): request = { session_id: "ydb://session/3?node_id=50002&id=ZTAxYTdjYjctZjExZjQ1M2MtZmFmNTNmNWYtNWYwYjNmNzg=" 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-11 14:47:06,499 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 3739649a-7ca7-4696-9f04-00d980b0eb2e, ghrun-l3u76ogmey.auto.internal:6685): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:06,500 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,500 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, bfe49ae0-8b9e-4e0a-9865-03e66cbeab73, ghrun-l3u76ogmey.auto.internal:6685): creating call state
2025-11-11 14:47:06,501 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, bfe49ae0-8b9e-4e0a-9865-03e66cbeab73, ghrun-l3u76ogmey.auto.internal:6685): request = { session_id: "ydb://session/3?node_id=50002&id=ZTAxYTdjYjctZjExZjQ1M2MtZmFmNTNmNWYtNWYwYjNmNzg=" 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-11 14:47:06,536 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, bfe49ae0-8b9e-4e0a-9865-03e66cbeab73, ghrun-l3u76ogmey.auto.internal:6685): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:06,537 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e663de34-95f7-4029-8dec-774969365883, ghrun-l3u76ogmey.auto.internal:6685): creating call state
2025-11-11 14:47:06,538 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e663de34-95f7-4029-8dec-774969365883, ghrun-l3u76ogmey.auto.internal:6685): request = { session_id: "ydb://session/3?node_id=50002&id=ZTAxYTdjYjctZjExZjQ1M2MtZmFmNTNmNWYtNWYwYjNmNzg=" 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-11 14:47:06,567 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e663de34-95f7-4029-8dec-774969365883, ghrun-l3u76ogmey.auto.internal:6685): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:47:06,611 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,612 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 1b93275d-11fc-4fb1-b63b-069f8fdb2252, ghrun-l3u76ogmey.auto.internal:6685): creating call state
2025-11-11 14:47:06,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 1b93275d-11fc-4fb1-b63b-069f8fdb2252, ghrun-l3u76ogmey.auto.internal:6685): request = { session_id: "ydb://session/3?node_id=50002&id=ZTAxYTdjYjctZjExZjQ1M2MtZmFmNTNmNWYtNWYwYjNmNzg=" 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-11 14:47:06,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 1b93275d-11fc-4fb1-b63b-069f8fdb2252, ghrun-l3u76ogmey.auto.internal:6685): 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-11 14:47:06,647 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,647 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 6b4b6b25-0636-402e-92b2-21e1ce69fb50, ghrun-l3u76ogmey.auto.internal:6685): creating call state
2025-11-11 14:47:06,648 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 6b4b6b25-0636-402e-92b2-21e1ce69fb50, ghrun-l3u76ogmey.auto.internal:6685): request = { session_id: "ydb://session/3?node_id=50002&id=ZTAxYTdjYjctZjExZjQ1M2MtZmFmNTNmNWYtNWYwYjNmNzg=" path: "/Root/users/database_2/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:47:06,686 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 6b4b6b25-0636-402e-92b2-21e1ce69fb50, ghrun-l3u76ogmey.auto.internal:6685): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:06,687 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,687 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:47:06,687 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:47:06,688 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:47:06,688 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f42514b0f10>, reason: session-pool-terminated
2025-11-11 14:47:06,688 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f42514b0f10> is no longer active. Current active count 0.
2025-11-11 14:47:06,688 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1d75d971-4a93-4820-9d25-5a3f4c7fd244, ghrun-l3u76ogmey.auto.internal:6685): creating call state
2025-11-11 14:47:06,689 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1d75d971-4a93-4820-9d25-5a3f4c7fd244, ghrun-l3u76ogmey.auto.internal:6685): request = { session_id: "ydb://session/3?node_id=50002&id=ZTAxYTdjYjctZjExZjQ1M2MtZmFmNTNmNWYtNWYwYjNmNzg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:06,690 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:47:06,690 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f42514b0f10>
2025-11-11 14:47:06,691 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:47:06,691 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:47:06,691 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:47:06,691 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:06,692 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:6685
2025-11-11 14:47:06,692 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:47:06,693 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_2
2025-11-11 14:47:06,692 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1d75d971-4a93-4820-9d25-5a3f4c7fd244, ghrun-l3u76ogmey.auto.internal:6685): on response callback started
2025-11-11 14:47:06,693 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_2: send console operation, token None
2025-11-11 14:47:06,694 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1d75d971-4a93-4820-9d25-5a3f4c7fd244, ghrun-l3u76ogmey.auto.internal:6685): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:06,695 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1d75d971-4a93-4820-9d25-5a3f4c7fd244, ghrun-l3u76ogmey.auto.internal:6685): on response callback success
2025-11-11 14:47:06,699 - 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=1762872426697542&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:47:06,700 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_2: wait console operation done
2025-11-11 14:47:07,212 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_2: console operation done
2025-11-11 14:47:07,213 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_2: wait tenant gone
2025-11-11 14:47:07,215 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_2: tenant gone
2025-11-11 14:47:08,220 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:6685/3
2025-11-11 14:47:08,748 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:47:08,749 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24774
2025-11-11 14:47:08,749 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12820
2025-11-11 14:47:08,749 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5333
2025-11-11 14:47:08,750 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5087
2025-11-11 14:47:08,750 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29840
2025-11-11 14:47:08,751 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:08,751 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_3 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_psbe8tav.log --grpc-port=24774 --mon-port=12820 --ic-port=5333
2025-11-11 14:47:08,752 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:08,752 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_3 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_psbe8tav.log --grpc-port=24774 --mon-port=12820 --ic-port=5333
2025-11-11 14:47:08,753 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_3', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_psbe8tav.log', '--grpc-port=24774', '--mon-port=12820', '--ic-port=5333')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:08,755 - DEBUG - ya.test - execute: Command pid: 158195
2025-11-11 14:47:08,756 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:24774/4
2025-11-11 14:47:08,757 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.23
2025-11-11 14:47:08,758 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.23
2025-11-11 14:47:08,758 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:47:08,760 - DEBUG - ya.test - execute: Command pid: 158198
2025-11-11 14:47:10,435 - DEBUG - ya.test - _save_outputs: Command (pid 158198) rc: 0
2025-11-11 14:47:10,435 - DEBUG - ya.test - _save_outputs: Command (pid 158198) elapsed time (sec): 1.673814058303833
2025-11-11 14:47:10,436 - DEBUG - ya.test - _save_outputs: Command (pid 158198) idrss: 0
2025-11-11 14:47:10,436 - DEBUG - ya.test - _save_outputs: Command (pid 158198) inblock: 0
2025-11-11 14:47:10,436 - DEBUG - ya.test - _save_outputs: Command (pid 158198) isrss: 0
2025-11-11 14:47:10,436 - DEBUG - ya.test - _save_outputs: Command (pid 158198) ixrss: 0
2025-11-11 14:47:10,436 - DEBUG - ya.test - _save_outputs: Command (pid 158198) majflt: 0
2025-11-11 14:47:10,437 - DEBUG - ya.test - _save_outputs: Command (pid 158198) maxrss: 543264
2025-11-11 14:47:10,437 - DEBUG - ya.test - _save_outputs: Command (pid 158198) minflt: 16226
2025-11-11 14:47:10,437 - DEBUG - ya.test - _save_outputs: Command (pid 158198) msgrcv: 0
2025-11-11 14:47:10,437 - DEBUG - ya.test - _save_outputs: Command (pid 158198) msgsnd: 0
2025-11-11 14:47:10,437 - DEBUG - ya.test - _save_outputs: Command (pid 158198) nivcsw: 208
2025-11-11 14:47:10,437 - DEBUG - ya.test - _save_outputs: Command (pid 158198) nsignals: 0
2025-11-11 14:47:10,437 - DEBUG - ya.test - _save_outputs: Command (pid 158198) nswap: 0
2025-11-11 14:47:10,438 - DEBUG - ya.test - _save_outputs: Command (pid 158198) nvcsw: 14
2025-11-11 14:47:10,438 - DEBUG - ya.test - _save_outputs: Command (pid 158198) oublock: 8
2025-11-11 14:47:10,438 - DEBUG - ya.test - _save_outputs: Command (pid 158198) stime: 0.740829
2025-11-11 14:47:10,438 - DEBUG - ya.test - _save_outputs: Command (pid 158198) utime: 0.108461
2025-11-11 14:47:10,438 - DEBUG - ya.test - _save_outputs: Command (pid 158198) wtime: 1.676
2025-11-11 14:47:10,438 - DEBUG - ya.test - _save_outputs: Command (pid 158198) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:47:10,439 - DEBUG - ya.test - _save_outputs: Command (pid 158198) errors:

2025-11-11 14:47:10,439 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 4 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:47:16,462 - 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-11 14:47:16,513 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:47:16,514 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:47:16,515 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-11 14:47:16,515 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4253826c20 state=pending>
2025-11-11 14:47:16,519 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:47:16,519 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,520 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,520 - 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-11 14:47:16,525 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_3
2025-11-11 14:47:16,525 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 37e123ea-d921-419d-853c-242e4b1f4eaa, localhost:8880): creating call state
2025-11-11 14:47:16,526 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 37e123ea-d921-419d-853c-242e4b1f4eaa, localhost:8880): request = { database: "/Root/users/database_3" }
2025-11-11 14:47:16,528 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 37e123ea-d921-419d-853c-242e4b1f4eaa, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 24774 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-11 14:47:16,529 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_3: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:24774, location , ssl: False>]>
2025-11-11 14:47:16,529 - 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-11 14:47:16,564 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:16,564 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,565 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5979ecd3-5ec6-472e-a94c-8cfccbe0acc7, ghrun-l3u76ogmey.auto.internal:24774): creating call state
2025-11-11 14:47:16,566 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5979ecd3-5ec6-472e-a94c-8cfccbe0acc7, ghrun-l3u76ogmey.auto.internal:24774): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:16,576 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5979ecd3-5ec6-472e-a94c-8cfccbe0acc7, ghrun-l3u76ogmey.auto.internal:24774): on response callback started
2025-11-11 14:47:16,578 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5979ecd3-5ec6-472e-a94c-8cfccbe0acc7, ghrun-l3u76ogmey.auto.internal:24774): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=ZGExZDFiZmYtM2JiMzYyMDItMTkyYWY3NjktYjQwN2JiY2U=" } } } }
2025-11-11 14:47:16,579 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,579 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,579 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5979ecd3-5ec6-472e-a94c-8cfccbe0acc7, ghrun-l3u76ogmey.auto.internal:24774): on response callback success
2025-11-11 14:47:16,580 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, dbe1bd04-5746-4018-8642-a8b12fec5751, ghrun-l3u76ogmey.auto.internal:24774): creating call state
2025-11-11 14:47:16,581 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, dbe1bd04-5746-4018-8642-a8b12fec5751, ghrun-l3u76ogmey.auto.internal:24774): request = { session_id: "ydb://session/3?node_id=50003&id=ZGExZDFiZmYtM2JiMzYyMDItMTkyYWY3NjktYjQwN2JiY2U=" 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-11 14:47:16,652 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, dbe1bd04-5746-4018-8642-a8b12fec5751, ghrun-l3u76ogmey.auto.internal:24774): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:16,653 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,653 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 731b7cdc-721c-4282-b867-bbb36f4c89e7, ghrun-l3u76ogmey.auto.internal:24774): creating call state
2025-11-11 14:47:16,654 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 731b7cdc-721c-4282-b867-bbb36f4c89e7, ghrun-l3u76ogmey.auto.internal:24774): request = { session_id: "ydb://session/3?node_id=50003&id=ZGExZDFiZmYtM2JiMzYyMDItMTkyYWY3NjktYjQwN2JiY2U=" 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-11 14:47:16,688 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 731b7cdc-721c-4282-b867-bbb36f4c89e7, ghrun-l3u76ogmey.auto.internal:24774): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:16,696 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,697 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 47896078-765a-448d-a573-c66136a309b3, ghrun-l3u76ogmey.auto.internal:24774): creating call state
2025-11-11 14:47:16,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 47896078-765a-448d-a573-c66136a309b3, ghrun-l3u76ogmey.auto.internal:24774): request = { session_id: "ydb://session/3?node_id=50003&id=ZGExZDFiZmYtM2JiMzYyMDItMTkyYWY3NjktYjQwN2JiY2U=" 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-11 14:47:16,775 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 47896078-765a-448d-a573-c66136a309b3, ghrun-l3u76ogmey.auto.internal:24774): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:47:16,775 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,776 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, bb52e84f-6000-4355-8754-a97c8e10ed75, ghrun-l3u76ogmey.auto.internal:24774): creating call state
2025-11-11 14:47:16,776 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, bb52e84f-6000-4355-8754-a97c8e10ed75, ghrun-l3u76ogmey.auto.internal:24774): request = { session_id: "ydb://session/3?node_id=50003&id=ZGExZDFiZmYtM2JiMzYyMDItMTkyYWY3NjktYjQwN2JiY2U=" 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-11 14:47:16,819 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, bb52e84f-6000-4355-8754-a97c8e10ed75, ghrun-l3u76ogmey.auto.internal:24774): 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-11 14:47:16,820 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,821 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, cb954bdb-4d1d-4aa3-9897-9e891e15b8c7, ghrun-l3u76ogmey.auto.internal:24774): creating call state
2025-11-11 14:47:16,821 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, cb954bdb-4d1d-4aa3-9897-9e891e15b8c7, ghrun-l3u76ogmey.auto.internal:24774): request = { session_id: "ydb://session/3?node_id=50003&id=ZGExZDFiZmYtM2JiMzYyMDItMTkyYWY3NjktYjQwN2JiY2U=" path: "/Root/users/database_3/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:47:16,859 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, cb954bdb-4d1d-4aa3-9897-9e891e15b8c7, ghrun-l3u76ogmey.auto.internal:24774): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:16,868 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,869 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:47:16,869 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:47:16,869 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:47:16,869 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f42501f84c0>, reason: session-pool-terminated
2025-11-11 14:47:16,869 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f42501f84c0> is no longer active. Current active count 0.
2025-11-11 14:47:16,869 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 35c663f3-3cb4-4bb3-8b78-68937212480b, ghrun-l3u76ogmey.auto.internal:24774): creating call state
2025-11-11 14:47:16,870 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 35c663f3-3cb4-4bb3-8b78-68937212480b, ghrun-l3u76ogmey.auto.internal:24774): request = { session_id: "ydb://session/3?node_id=50003&id=ZGExZDFiZmYtM2JiMzYyMDItMTkyYWY3NjktYjQwN2JiY2U=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:16,871 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f42501f84c0>
2025-11-11 14:47:16,872 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:47:16,872 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:47:16,873 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 35c663f3-3cb4-4bb3-8b78-68937212480b, ghrun-l3u76ogmey.auto.internal:24774): on response callback started
2025-11-11 14:47:16,873 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 35c663f3-3cb4-4bb3-8b78-68937212480b, ghrun-l3u76ogmey.auto.internal:24774): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:16,873 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 35c663f3-3cb4-4bb3-8b78-68937212480b, ghrun-l3u76ogmey.auto.internal:24774): on response callback success
2025-11-11 14:47:16,874 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:47:16,874 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:47:16,874 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:16,875 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:24774
2025-11-11 14:47:16,875 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:47:16,876 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_3
2025-11-11 14:47:16,876 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_3: send console operation, token None
2025-11-11 14:47:16,883 - 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=1762872436879124&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:47:16,884 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_3: wait console operation done
2025-11-11 14:47:17,387 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_3: console operation done
2025-11-11 14:47:17,388 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_3: wait tenant gone
2025-11-11 14:47:17,392 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_3: tenant gone
2025-11-11 14:47:18,394 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:24774/4
2025-11-11 14:47:18,912 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:47:18,912 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9385
2025-11-11 14:47:18,913 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6050
2025-11-11 14:47:18,913 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3637
2025-11-11 14:47:18,913 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6422
2025-11-11 14:47:18,913 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28119
2025-11-11 14:47:18,914 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:18,914 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_4 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_6xkdfxq0.log --grpc-port=9385 --mon-port=6050 --ic-port=3637
2025-11-11 14:47:18,915 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:18,915 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_4 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_6xkdfxq0.log --grpc-port=9385 --mon-port=6050 --ic-port=3637
2025-11-11 14:47:18,916 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_4', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_6xkdfxq0.log', '--grpc-port=9385', '--mon-port=6050', '--ic-port=3637')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:18,926 - DEBUG - ya.test - execute: Command pid: 163178
2025-11-11 14:47:18,927 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:9385/5
2025-11-11 14:47:18,928 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.24
2025-11-11 14:47:18,929 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.24
2025-11-11 14:47:18,929 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:47:18,930 - DEBUG - ya.test - execute: Command pid: 163189
2025-11-11 14:47:19,412 - DEBUG - ya.test - _save_outputs: Command (pid 163189) rc: 0
2025-11-11 14:47:19,412 - DEBUG - ya.test - _save_outputs: Command (pid 163189) elapsed time (sec): 0.4203832149505615
2025-11-11 14:47:19,412 - DEBUG - ya.test - _save_outputs: Command (pid 163189) idrss: 0
2025-11-11 14:47:19,413 - DEBUG - ya.test - _save_outputs: Command (pid 163189) inblock: 0
2025-11-11 14:47:19,413 - DEBUG - ya.test - _save_outputs: Command (pid 163189) isrss: 0
2025-11-11 14:47:19,413 - DEBUG - ya.test - _save_outputs: Command (pid 163189) ixrss: 0
2025-11-11 14:47:19,413 - DEBUG - ya.test - _save_outputs: Command (pid 163189) majflt: 0
2025-11-11 14:47:19,413 - DEBUG - ya.test - _save_outputs: Command (pid 163189) maxrss: 543264
2025-11-11 14:47:19,413 - DEBUG - ya.test - _save_outputs: Command (pid 163189) minflt: 16229
2025-11-11 14:47:19,413 - DEBUG - ya.test - _save_outputs: Command (pid 163189) msgrcv: 0
2025-11-11 14:47:19,414 - DEBUG - ya.test - _save_outputs: Command (pid 163189) msgsnd: 0
2025-11-11 14:47:19,414 - DEBUG - ya.test - _save_outputs: Command (pid 163189) nivcsw: 60
2025-11-11 14:47:19,414 - DEBUG - ya.test - _save_outputs: Command (pid 163189) nsignals: 0
2025-11-11 14:47:19,414 - DEBUG - ya.test - _save_outputs: Command (pid 163189) nswap: 0
2025-11-11 14:47:19,414 - DEBUG - ya.test - _save_outputs: Command (pid 163189) nvcsw: 13
2025-11-11 14:47:19,414 - DEBUG - ya.test - _save_outputs: Command (pid 163189) oublock: 8
2025-11-11 14:47:19,414 - DEBUG - ya.test - _save_outputs: Command (pid 163189) stime: 0.179787
2025-11-11 14:47:19,415 - DEBUG - ya.test - _save_outputs: Command (pid 163189) utime: 0.12240799999999999
2025-11-11 14:47:19,415 - DEBUG - ya.test - _save_outputs: Command (pid 163189) wtime: 0.422
2025-11-11 14:47:19,415 - DEBUG - ya.test - _save_outputs: Command (pid 163189) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:47:19,415 - DEBUG - ya.test - _save_outputs: Command (pid 163189) errors:

2025-11-11 14:47:19,416 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 5 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:47:21,430 - 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-11 14:47:21,432 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:47:21,432 - 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-11 14:47:21,435 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:47:21,436 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f424fccfc10 state=pending>
2025-11-11 14:47:21,436 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:47:21,436 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,436 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,437 - 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-11 14:47:21,452 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_4
2025-11-11 14:47:21,453 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, aab4c1e6-8141-4f26-8ae4-08f85877c7bc, localhost:8880): creating call state
2025-11-11 14:47:21,453 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, aab4c1e6-8141-4f26-8ae4-08f85877c7bc, localhost:8880): request = { database: "/Root/users/database_4" }
2025-11-11 14:47:21,457 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, aab4c1e6-8141-4f26-8ae4-08f85877c7bc, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 9385 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-11 14:47:21,457 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_4: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:9385, location , ssl: False>]>
2025-11-11 14:47:21,458 - 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-11 14:47:21,480 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:21,480 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,481 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6038a6c6-67d1-4339-807e-d55d2b9d05bd, ghrun-l3u76ogmey.auto.internal:9385): creating call state
2025-11-11 14:47:21,481 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6038a6c6-67d1-4339-807e-d55d2b9d05bd, ghrun-l3u76ogmey.auto.internal:9385): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:21,485 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6038a6c6-67d1-4339-807e-d55d2b9d05bd, ghrun-l3u76ogmey.auto.internal:9385): on response callback started
2025-11-11 14:47:21,486 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6038a6c6-67d1-4339-807e-d55d2b9d05bd, ghrun-l3u76ogmey.auto.internal:9385): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50004&id=ZWQ3NjJlMTYtY2YxOTk0ZTEtZDVlZDlmZGItZTc3ODg5NmQ=" } } } }
2025-11-11 14:47:21,486 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,486 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,486 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 6038a6c6-67d1-4339-807e-d55d2b9d05bd, ghrun-l3u76ogmey.auto.internal:9385): on response callback success
2025-11-11 14:47:21,488 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 57f81519-a040-4ed5-883e-f2e240523c76, ghrun-l3u76ogmey.auto.internal:9385): creating call state
2025-11-11 14:47:21,488 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 57f81519-a040-4ed5-883e-f2e240523c76, ghrun-l3u76ogmey.auto.internal:9385): request = { session_id: "ydb://session/3?node_id=50004&id=ZWQ3NjJlMTYtY2YxOTk0ZTEtZDVlZDlmZGItZTc3ODg5NmQ=" 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-11 14:47:21,629 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 57f81519-a040-4ed5-883e-f2e240523c76, ghrun-l3u76ogmey.auto.internal:9385): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:21,679 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,680 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, d289eb1b-0bc5-48bf-93f6-fa2ed22d9feb, ghrun-l3u76ogmey.auto.internal:9385): creating call state
2025-11-11 14:47:21,681 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, d289eb1b-0bc5-48bf-93f6-fa2ed22d9feb, ghrun-l3u76ogmey.auto.internal:9385): request = { session_id: "ydb://session/3?node_id=50004&id=ZWQ3NjJlMTYtY2YxOTk0ZTEtZDVlZDlmZGItZTc3ODg5NmQ=" 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-11 14:47:21,724 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, d289eb1b-0bc5-48bf-93f6-fa2ed22d9feb, ghrun-l3u76ogmey.auto.internal:9385): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:21,724 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,725 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 07f747c5-e9bf-47a2-9fca-1a176e1332cd, ghrun-l3u76ogmey.auto.internal:9385): creating call state
2025-11-11 14:47:21,725 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 07f747c5-e9bf-47a2-9fca-1a176e1332cd, ghrun-l3u76ogmey.auto.internal:9385): request = { session_id: "ydb://session/3?node_id=50004&id=ZWQ3NjJlMTYtY2YxOTk0ZTEtZDVlZDlmZGItZTc3ODg5NmQ=" 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-11 14:47:21,767 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 07f747c5-e9bf-47a2-9fca-1a176e1332cd, ghrun-l3u76ogmey.auto.internal:9385): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:47:21,768 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,769 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6d02448c-ab6e-4e0f-a9cf-198b22fe3650, ghrun-l3u76ogmey.auto.internal:9385): creating call state
2025-11-11 14:47:21,769 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6d02448c-ab6e-4e0f-a9cf-198b22fe3650, ghrun-l3u76ogmey.auto.internal:9385): request = { session_id: "ydb://session/3?node_id=50004&id=ZWQ3NjJlMTYtY2YxOTk0ZTEtZDVlZDlmZGItZTc3ODg5NmQ=" 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-11 14:47:21,814 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6d02448c-ab6e-4e0f-a9cf-198b22fe3650, ghrun-l3u76ogmey.auto.internal:9385): 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-11 14:47:21,814 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,814 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 255f1647-dbef-43b1-8515-93f17c2fed6a, ghrun-l3u76ogmey.auto.internal:9385): creating call state
2025-11-11 14:47:21,815 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 255f1647-dbef-43b1-8515-93f17c2fed6a, ghrun-l3u76ogmey.auto.internal:9385): request = { session_id: "ydb://session/3?node_id=50004&id=ZWQ3NjJlMTYtY2YxOTk0ZTEtZDVlZDlmZGItZTc3ODg5NmQ=" path: "/Root/users/database_4/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:47:21,841 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 255f1647-dbef-43b1-8515-93f17c2fed6a, ghrun-l3u76ogmey.auto.internal:9385): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:21,841 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,842 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:47:21,842 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:47:21,842 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:47:21,842 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f42513dfe40>, reason: session-pool-terminated
2025-11-11 14:47:21,842 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f42513dfe40> is no longer active. Current active count 0.
2025-11-11 14:47:21,842 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 205a947c-8542-43aa-b7df-b5841fefb18e, ghrun-l3u76ogmey.auto.internal:9385): creating call state
2025-11-11 14:47:21,843 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 205a947c-8542-43aa-b7df-b5841fefb18e, ghrun-l3u76ogmey.auto.internal:9385): request = { session_id: "ydb://session/3?node_id=50004&id=ZWQ3NjJlMTYtY2YxOTk0ZTEtZDVlZDlmZGItZTc3ODg5NmQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:21,843 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:47:21,845 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f42513dfe40>
2025-11-11 14:47:21,845 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:47:21,845 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:47:21,846 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:47:21,847 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:21,847 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:9385
2025-11-11 14:47:21,847 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:47:21,848 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 205a947c-8542-43aa-b7df-b5841fefb18e, ghrun-l3u76ogmey.auto.internal:9385): on response callback started
2025-11-11 14:47:21,849 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 205a947c-8542-43aa-b7df-b5841fefb18e, ghrun-l3u76ogmey.auto.internal:9385): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:21,849 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 205a947c-8542-43aa-b7df-b5841fefb18e, ghrun-l3u76ogmey.auto.internal:9385): on response callback success
2025-11-11 14:47:21,851 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_4
2025-11-11 14:47:21,851 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_4: send console operation, token None
2025-11-11 14:47:21,858 - 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=1762872441851577&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:47:21,858 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_4: wait console operation done
2025-11-11 14:47:22,364 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_4: console operation done
2025-11-11 14:47:22,365 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_4: wait tenant gone
2025-11-11 14:47:22,368 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_4: tenant gone
2025-11-11 14:47:25,372 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:9385/5
2025-11-11 14:47:25,901 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:47:25,901 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11435
2025-11-11 14:47:25,902 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10253
2025-11-11 14:47:25,902 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8479
2025-11-11 14:47:25,903 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18680
2025-11-11 14:47:25,903 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24989
2025-11-11 14:47:25,904 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:25,904 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_5 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_urrzd243.log --grpc-port=11435 --mon-port=10253 --ic-port=8479
2025-11-11 14:47:25,904 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:25,904 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_5 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_urrzd243.log --grpc-port=11435 --mon-port=10253 --ic-port=8479
2025-11-11 14:47:25,905 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_5', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_urrzd243.log', '--grpc-port=11435', '--mon-port=10253', '--ic-port=8479')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:25,915 - DEBUG - ya.test - execute: Command pid: 171516
2025-11-11 14:47:25,916 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:11435/6
2025-11-11 14:47:25,917 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.25
2025-11-11 14:47:25,918 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.25
2025-11-11 14:47:25,919 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:47:25,928 - DEBUG - ya.test - execute: Command pid: 171525
2025-11-11 14:47:26,601 - DEBUG - ya.test - _save_outputs: Command (pid 171525) rc: 0
2025-11-11 14:47:26,601 - DEBUG - ya.test - _save_outputs: Command (pid 171525) elapsed time (sec): 0.6713595390319824
2025-11-11 14:47:26,601 - DEBUG - ya.test - _save_outputs: Command (pid 171525) idrss: 0
2025-11-11 14:47:26,602 - DEBUG - ya.test - _save_outputs: Command (pid 171525) inblock: 0
2025-11-11 14:47:26,602 - DEBUG - ya.test - _save_outputs: Command (pid 171525) isrss: 0
2025-11-11 14:47:26,602 - DEBUG - ya.test - _save_outputs: Command (pid 171525) ixrss: 0
2025-11-11 14:47:26,602 - DEBUG - ya.test - _save_outputs: Command (pid 171525) majflt: 0
2025-11-11 14:47:26,602 - DEBUG - ya.test - _save_outputs: Command (pid 171525) maxrss: 543264
2025-11-11 14:47:26,603 - DEBUG - ya.test - _save_outputs: Command (pid 171525) minflt: 16225
2025-11-11 14:47:26,603 - DEBUG - ya.test - _save_outputs: Command (pid 171525) msgrcv: 0
2025-11-11 14:47:26,603 - DEBUG - ya.test - _save_outputs: Command (pid 171525) msgsnd: 0
2025-11-11 14:47:26,603 - DEBUG - ya.test - _save_outputs: Command (pid 171525) nivcsw: 109
2025-11-11 14:47:26,603 - DEBUG - ya.test - _save_outputs: Command (pid 171525) nsignals: 0
2025-11-11 14:47:26,603 - DEBUG - ya.test - _save_outputs: Command (pid 171525) nswap: 0
2025-11-11 14:47:26,604 - DEBUG - ya.test - _save_outputs: Command (pid 171525) nvcsw: 13
2025-11-11 14:47:26,604 - DEBUG - ya.test - _save_outputs: Command (pid 171525) oublock: 8
2025-11-11 14:47:26,604 - DEBUG - ya.test - _save_outputs: Command (pid 171525) stime: 0.374
2025-11-11 14:47:26,604 - DEBUG - ya.test - _save_outputs: Command (pid 171525) utime: 0.109064
2025-11-11 14:47:26,604 - DEBUG - ya.test - _save_outputs: Command (pid 171525) wtime: 0.682
2025-11-11 14:47:26,605 - DEBUG - ya.test - _save_outputs: Command (pid 171525) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:47:26,605 - DEBUG - ya.test - _save_outputs: Command (pid 171525) errors:

2025-11-11 14:47:26,605 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 6 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:47:28,621 - 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-11 14:47:28,637 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:47:28,637 - 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-11 14:47:28,650 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_5
2025-11-11 14:47:28,651 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 0230be84-08bf-4896-a0de-9eddf12dfc2f, localhost:8880): creating call state
2025-11-11 14:47:28,651 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 0230be84-08bf-4896-a0de-9eddf12dfc2f, localhost:8880): request = { database: "/Root/users/database_5" }
2025-11-11 14:47:28,655 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 0230be84-08bf-4896-a0de-9eddf12dfc2f, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 11435 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-11 14:47:28,655 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_5: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:11435, location , ssl: False>]>
2025-11-11 14:47:28,655 - 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-11 14:47:28,664 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:47:28,664 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f42502b3530 state=pending>
2025-11-11 14:47:28,664 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:47:28,665 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,665 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,665 - 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-11 14:47:28,678 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:28,685 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,686 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 94efb17b-652e-4579-9cba-4afb9bf83c91, ghrun-l3u76ogmey.auto.internal:11435): creating call state
2025-11-11 14:47:28,686 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 94efb17b-652e-4579-9cba-4afb9bf83c91, ghrun-l3u76ogmey.auto.internal:11435): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:28,697 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 94efb17b-652e-4579-9cba-4afb9bf83c91, ghrun-l3u76ogmey.auto.internal:11435): on response callback started
2025-11-11 14:47:28,697 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 94efb17b-652e-4579-9cba-4afb9bf83c91, ghrun-l3u76ogmey.auto.internal:11435): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50005&id=NTllMjIxYzYtYzhjYTQ1MjUtN2RkMDY2NWQtZjc3ZTFj" } } } }
2025-11-11 14:47:28,698 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 94efb17b-652e-4579-9cba-4afb9bf83c91, ghrun-l3u76ogmey.auto.internal:11435): on response callback success
2025-11-11 14:47:28,698 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,698 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,699 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, bba1af96-c8c8-49ea-a7c4-c029fe32a9eb, ghrun-l3u76ogmey.auto.internal:11435): creating call state
2025-11-11 14:47:28,699 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, bba1af96-c8c8-49ea-a7c4-c029fe32a9eb, ghrun-l3u76ogmey.auto.internal:11435): request = { session_id: "ydb://session/3?node_id=50005&id=NTllMjIxYzYtYzhjYTQ1MjUtN2RkMDY2NWQtZjc3ZTFj" 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-11 14:47:28,805 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, bba1af96-c8c8-49ea-a7c4-c029fe32a9eb, ghrun-l3u76ogmey.auto.internal:11435): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:28,806 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,806 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 3a7c22a7-1bc4-4504-bf03-67a932df7ec1, ghrun-l3u76ogmey.auto.internal:11435): creating call state
2025-11-11 14:47:28,807 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 3a7c22a7-1bc4-4504-bf03-67a932df7ec1, ghrun-l3u76ogmey.auto.internal:11435): request = { session_id: "ydb://session/3?node_id=50005&id=NTllMjIxYzYtYzhjYTQ1MjUtN2RkMDY2NWQtZjc3ZTFj" 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-11 14:47:28,883 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 3a7c22a7-1bc4-4504-bf03-67a932df7ec1, ghrun-l3u76ogmey.auto.internal:11435): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:28,883 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 631d7885-bb29-455f-9563-9a7277df823c, ghrun-l3u76ogmey.auto.internal:11435): creating call state
2025-11-11 14:47:28,885 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 631d7885-bb29-455f-9563-9a7277df823c, ghrun-l3u76ogmey.auto.internal:11435): request = { session_id: "ydb://session/3?node_id=50005&id=NTllMjIxYzYtYzhjYTQ1MjUtN2RkMDY2NWQtZjc3ZTFj" 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-11 14:47:28,934 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 631d7885-bb29-455f-9563-9a7277df823c, ghrun-l3u76ogmey.auto.internal:11435): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:47:28,935 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:28,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, b7acc356-30d6-45c7-bca1-130e1c337214, ghrun-l3u76ogmey.auto.internal:11435): creating call state
2025-11-11 14:47:28,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, b7acc356-30d6-45c7-bca1-130e1c337214, ghrun-l3u76ogmey.auto.internal:11435): request = { session_id: "ydb://session/3?node_id=50005&id=NTllMjIxYzYtYzhjYTQ1MjUtN2RkMDY2NWQtZjc3ZTFj" 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-11 14:47:29,015 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, b7acc356-30d6-45c7-bca1-130e1c337214, ghrun-l3u76ogmey.auto.internal:11435): 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-11 14:47:29,016 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:29,016 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, c943b26d-415e-487f-9a4a-e12ccf13e441, ghrun-l3u76ogmey.auto.internal:11435): creating call state
2025-11-11 14:47:29,016 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, c943b26d-415e-487f-9a4a-e12ccf13e441, ghrun-l3u76ogmey.auto.internal:11435): request = { session_id: "ydb://session/3?node_id=50005&id=NTllMjIxYzYtYzhjYTQ1MjUtN2RkMDY2NWQtZjc3ZTFj" path: "/Root/users/database_5/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:47:29,074 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, c943b26d-415e-487f-9a4a-e12ccf13e441, ghrun-l3u76ogmey.auto.internal:11435): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:29,156 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:29,157 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:47:29,157 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:47:29,157 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:47:29,157 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f42502b3440>, reason: session-pool-terminated
2025-11-11 14:47:29,157 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f42502b3440> is no longer active. Current active count 0.
2025-11-11 14:47:29,158 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 6bdd7d15-a960-4022-9bf5-890e0695ff72, ghrun-l3u76ogmey.auto.internal:11435): creating call state
2025-11-11 14:47:29,158 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 6bdd7d15-a960-4022-9bf5-890e0695ff72, ghrun-l3u76ogmey.auto.internal:11435): request = { session_id: "ydb://session/3?node_id=50005&id=NTllMjIxYzYtYzhjYTQ1MjUtN2RkMDY2NWQtZjc3ZTFj" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:29,159 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f42502b3440>
2025-11-11 14:47:29,160 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:47:29,161 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:47:29,161 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:47:29,161 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:47:29,162 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:29,162 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 6bdd7d15-a960-4022-9bf5-890e0695ff72, ghrun-l3u76ogmey.auto.internal:11435): on response callback started
2025-11-11 14:47:29,163 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 6bdd7d15-a960-4022-9bf5-890e0695ff72, ghrun-l3u76ogmey.auto.internal:11435): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:29,163 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 6bdd7d15-a960-4022-9bf5-890e0695ff72, ghrun-l3u76ogmey.auto.internal:11435): on response callback success
2025-11-11 14:47:29,164 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:11435
2025-11-11 14:47:29,164 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:47:29,165 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_5
2025-11-11 14:47:29,165 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_5: send console operation, token None
2025-11-11 14:47:29,182 - 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=1762872449171968&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:47:29,182 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_5: wait console operation done
2025-11-11 14:47:29,691 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_5: console operation done
2025-11-11 14:47:29,692 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_5: wait tenant gone
2025-11-11 14:47:29,695 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_5: tenant gone
2025-11-11 14:47:32,700 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11435/6
2025-11-11 14:47:33,230 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:47:33,231 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18997
2025-11-11 14:47:33,231 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8095
2025-11-11 14:47:33,232 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9069
2025-11-11 14:47:33,232 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8620
2025-11-11 14:47:33,232 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9473
2025-11-11 14:47:33,233 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:33,233 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_6 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_ooksluq8.log --grpc-port=18997 --mon-port=8095 --ic-port=9069
2025-11-11 14:47:33,233 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:33,233 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_6 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_ooksluq8.log --grpc-port=18997 --mon-port=8095 --ic-port=9069
2025-11-11 14:47:33,234 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_6', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_ooksluq8.log', '--grpc-port=18997', '--mon-port=8095', '--ic-port=9069')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:33,237 - DEBUG - ya.test - execute: Command pid: 184639
2025-11-11 14:47:33,237 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18997/7
2025-11-11 14:47:33,238 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.26
2025-11-11 14:47:33,239 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.26
2025-11-11 14:47:33,239 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:47:33,242 - DEBUG - ya.test - execute: Command pid: 184642
2025-11-11 14:47:34,617 - DEBUG - ya.test - _save_outputs: Command (pid 184642) rc: 0
2025-11-11 14:47:34,618 - DEBUG - ya.test - _save_outputs: Command (pid 184642) elapsed time (sec): 1.3722903728485107
2025-11-11 14:47:34,618 - DEBUG - ya.test - _save_outputs: Command (pid 184642) idrss: 0
2025-11-11 14:47:34,618 - DEBUG - ya.test - _save_outputs: Command (pid 184642) inblock: 0
2025-11-11 14:47:34,618 - DEBUG - ya.test - _save_outputs: Command (pid 184642) isrss: 0
2025-11-11 14:47:34,618 - DEBUG - ya.test - _save_outputs: Command (pid 184642) ixrss: 0
2025-11-11 14:47:34,618 - DEBUG - ya.test - _save_outputs: Command (pid 184642) majflt: 0
2025-11-11 14:47:34,618 - DEBUG - ya.test - _save_outputs: Command (pid 184642) maxrss: 543264
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) minflt: 16224
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) msgrcv: 0
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) msgsnd: 0
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) nivcsw: 215
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) nsignals: 0
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) nswap: 0
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) nvcsw: 13
2025-11-11 14:47:34,619 - DEBUG - ya.test - _save_outputs: Command (pid 184642) oublock: 8
2025-11-11 14:47:34,620 - DEBUG - ya.test - _save_outputs: Command (pid 184642) stime: 0.6233639999999999
2025-11-11 14:47:34,620 - DEBUG - ya.test - _save_outputs: Command (pid 184642) utime: 0.128799
2025-11-11 14:47:34,620 - DEBUG - ya.test - _save_outputs: Command (pid 184642) wtime: 1.376
2025-11-11 14:47:34,620 - DEBUG - ya.test - _save_outputs: Command (pid 184642) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:47:34,620 - DEBUG - ya.test - _save_outputs: Command (pid 184642) errors:

2025-11-11 14:47:34,620 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 7 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:47:40,643 - 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-11 14:47:40,652 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:47:40,653 - 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-11 14:47:40,668 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:47:40,668 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_6
2025-11-11 14:47:40,671 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 7fb1fe12-461f-48e2-9f4c-b8898c08e8aa, localhost:8880): creating call state
2025-11-11 14:47:40,671 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 7fb1fe12-461f-48e2-9f4c-b8898c08e8aa, localhost:8880): request = { database: "/Root/users/database_6" }
2025-11-11 14:47:40,675 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 7fb1fe12-461f-48e2-9f4c-b8898c08e8aa, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 18997 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-11 14:47:40,675 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_6: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:18997, location , ssl: False>]>
2025-11-11 14:47:40,670 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f42514014b0 state=pending>
2025-11-11 14:47:40,676 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:47:40,676 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:40,676 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:40,677 - 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-11 14:47:40,676 - 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-11 14:47:40,705 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:40,706 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:40,706 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d657e73c-6d0c-44ad-936c-f988691c0ac8, ghrun-l3u76ogmey.auto.internal:18997): creating call state
2025-11-11 14:47:40,707 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d657e73c-6d0c-44ad-936c-f988691c0ac8, ghrun-l3u76ogmey.auto.internal:18997): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:40,714 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d657e73c-6d0c-44ad-936c-f988691c0ac8, ghrun-l3u76ogmey.auto.internal:18997): on response callback started
2025-11-11 14:47:40,714 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d657e73c-6d0c-44ad-936c-f988691c0ac8, ghrun-l3u76ogmey.auto.internal:18997): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50006&id=MmEwZjc5MDAtNzk2ODg5MDMtNjVkMDE1ODQtN2ZjNjRlOWQ=" } } } }
2025-11-11 14:47:40,714 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:40,715 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:40,715 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d657e73c-6d0c-44ad-936c-f988691c0ac8, ghrun-l3u76ogmey.auto.internal:18997): on response callback success
2025-11-11 14:47:40,715 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, aa26ebca-f845-4673-b957-577a041a284a, ghrun-l3u76ogmey.auto.internal:18997): creating call state
2025-11-11 14:47:40,716 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, aa26ebca-f845-4673-b957-577a041a284a, ghrun-l3u76ogmey.auto.internal:18997): request = { session_id: "ydb://session/3?node_id=50006&id=MmEwZjc5MDAtNzk2ODg5MDMtNjVkMDE1ODQtN2ZjNjRlOWQ=" 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-11 14:47:40,856 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, aa26ebca-f845-4673-b957-577a041a284a, ghrun-l3u76ogmey.auto.internal:18997): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:40,896 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:40,897 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 087194f9-a97f-4403-b3cf-e3e7b2f0e64a, ghrun-l3u76ogmey.auto.internal:18997): creating call state
2025-11-11 14:47:40,898 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 087194f9-a97f-4403-b3cf-e3e7b2f0e64a, ghrun-l3u76ogmey.auto.internal:18997): request = { session_id: "ydb://session/3?node_id=50006&id=MmEwZjc5MDAtNzk2ODg5MDMtNjVkMDE1ODQtN2ZjNjRlOWQ=" 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-11 14:47:41,025 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 087194f9-a97f-4403-b3cf-e3e7b2f0e64a, ghrun-l3u76ogmey.auto.internal:18997): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:41,026 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:41,026 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, a1be9a2b-1d38-4f90-bbd5-7e79f885da37, ghrun-l3u76ogmey.auto.internal:18997): creating call state
2025-11-11 14:47:41,027 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, a1be9a2b-1d38-4f90-bbd5-7e79f885da37, ghrun-l3u76ogmey.auto.internal:18997): request = { session_id: "ydb://session/3?node_id=50006&id=MmEwZjc5MDAtNzk2ODg5MDMtNjVkMDE1ODQtN2ZjNjRlOWQ=" 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-11 14:47:41,074 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, a1be9a2b-1d38-4f90-bbd5-7e79f885da37, ghrun-l3u76ogmey.auto.internal:18997): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:47:41,149 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:41,150 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 61fc3ad1-4340-4c6b-b220-9ccec2d26862, ghrun-l3u76ogmey.auto.internal:18997): creating call state
2025-11-11 14:47:41,151 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 61fc3ad1-4340-4c6b-b220-9ccec2d26862, ghrun-l3u76ogmey.auto.internal:18997): request = { session_id: "ydb://session/3?node_id=50006&id=MmEwZjc5MDAtNzk2ODg5MDMtNjVkMDE1ODQtN2ZjNjRlOWQ=" 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-11 14:47:41,207 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 61fc3ad1-4340-4c6b-b220-9ccec2d26862, ghrun-l3u76ogmey.auto.internal:18997): 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-11 14:47:41,210 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:41,210 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, e82a7e7e-6830-45b6-8a14-05978d0a4c56, ghrun-l3u76ogmey.auto.internal:18997): creating call state
2025-11-11 14:47:41,211 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, e82a7e7e-6830-45b6-8a14-05978d0a4c56, ghrun-l3u76ogmey.auto.internal:18997): request = { session_id: "ydb://session/3?node_id=50006&id=MmEwZjc5MDAtNzk2ODg5MDMtNjVkMDE1ODQtN2ZjNjRlOWQ=" path: "/Root/users/database_6/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:47:41,261 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, e82a7e7e-6830-45b6-8a14-05978d0a4c56, ghrun-l3u76ogmey.auto.internal:18997): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:41,261 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:41,261 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:47:41,262 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:47:41,262 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:47:41,262 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4251402040>, reason: session-pool-terminated
2025-11-11 14:47:41,262 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4251402040> is no longer active. Current active count 0.
2025-11-11 14:47:41,262 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 6c7402fb-0a3b-4306-946a-f56902dc876d, ghrun-l3u76ogmey.auto.internal:18997): creating call state
2025-11-11 14:47:41,263 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 6c7402fb-0a3b-4306-946a-f56902dc876d, ghrun-l3u76ogmey.auto.internal:18997): request = { session_id: "ydb://session/3?node_id=50006&id=MmEwZjc5MDAtNzk2ODg5MDMtNjVkMDE1ODQtN2ZjNjRlOWQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:41,284 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:47:41,297 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 6c7402fb-0a3b-4306-946a-f56902dc876d, ghrun-l3u76ogmey.auto.internal:18997): on response callback started
2025-11-11 14:47:41,297 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 6c7402fb-0a3b-4306-946a-f56902dc876d, ghrun-l3u76ogmey.auto.internal:18997): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:41,298 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 6c7402fb-0a3b-4306-946a-f56902dc876d, ghrun-l3u76ogmey.auto.internal:18997): on response callback success
2025-11-11 14:47:41,298 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4251402040>
2025-11-11 14:47:41,298 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:47:41,298 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:47:41,299 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:47:41,299 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:41,301 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:18997
2025-11-11 14:47:41,301 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:47:41,308 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_6
2025-11-11 14:47:41,308 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_6: send console operation, token None
2025-11-11 14:47:41,331 - 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=1762872461317478&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:47:41,331 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_6: wait console operation done
2025-11-11 14:47:41,845 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_6: console operation done
2025-11-11 14:47:41,845 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_6: wait tenant gone
2025-11-11 14:47:41,853 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_6: tenant gone
2025-11-11 14:47:44,880 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:18997/7
2025-11-11 14:47:45,410 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd
2025-11-11 14:47:45,417 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62457
2025-11-11 14:47:45,417 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1673
2025-11-11 14:47:45,417 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22945
2025-11-11 14:47:45,418 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23635
2025-11-11 14:47:45,418 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17331
2025-11-11 14:47:45,419 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:45,419 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_7 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_w5mujgeh.log --grpc-port=62457 --mon-port=1673 --ic-port=22945
2025-11-11 14:47:45,419 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:45,429 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:8880 --tenant=/Root/users/database_7 --log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_w5mujgeh.log --grpc-port=62457 --mon-port=1673 --ic-port=22945
2025-11-11 14:47:45,431 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bs45/002c15/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:8880', '--tenant=/Root/users/database_7', '--log-file-name=/home/runner/.ya/build/build_root/bs45/002c15/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_w5mujgeh.log', '--grpc-port=62457', '--mon-port=1673', '--ic-port=22945')' in '/home/runner/.ya/build/build_root/bs45/002c15/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-11 14:47:45,446 - DEBUG - ya.test - execute: Command pid: 201752
2025-11-11 14:47:45,447 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:62457/8
2025-11-11 14:47:45,448 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.27
2025-11-11 14:47:45,449 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.27
2025-11-11 14:47:45,449 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bs45/002c15/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 14:47:45,460 - DEBUG - ya.test - execute: Command pid: 201795
2025-11-11 14:47:46,157 - DEBUG - ya.test - _save_outputs: Command (pid 201795) rc: 0
2025-11-11 14:47:46,158 - DEBUG - ya.test - _save_outputs: Command (pid 201795) elapsed time (sec): 0.6954209804534912
2025-11-11 14:47:46,158 - DEBUG - ya.test - _save_outputs: Command (pid 201795) idrss: 0
2025-11-11 14:47:46,158 - DEBUG - ya.test - _save_outputs: Command (pid 201795) inblock: 904
2025-11-11 14:47:46,158 - DEBUG - ya.test - _save_outputs: Command (pid 201795) isrss: 0
2025-11-11 14:47:46,158 - DEBUG - ya.test - _save_outputs: Command (pid 201795) ixrss: 0
2025-11-11 14:47:46,158 - DEBUG - ya.test - _save_outputs: Command (pid 201795) majflt: 1
2025-11-11 14:47:46,159 - DEBUG - ya.test - _save_outputs: Command (pid 201795) maxrss: 543264
2025-11-11 14:47:46,159 - DEBUG - ya.test - _save_outputs: Command (pid 201795) minflt: 16227
2025-11-11 14:47:46,159 - DEBUG - ya.test - _save_outputs: Command (pid 201795) msgrcv: 0
2025-11-11 14:47:46,159 - DEBUG - ya.test - _save_outputs: Command (pid 201795) msgsnd: 0
2025-11-11 14:47:46,159 - DEBUG - ya.test - _save_outputs: Command (pid 201795) nivcsw: 81
2025-11-11 14:47:46,159 - DEBUG - ya.test - _save_outputs: Command (pid 201795) nsignals: 0
2025-11-11 14:47:46,160 - DEBUG - ya.test - _save_outputs: Command (pid 201795) nswap: 0
2025-11-11 14:47:46,160 - DEBUG - ya.test - _save_outputs: Command (pid 201795) nvcsw: 15
2025-11-11 14:47:46,160 - DEBUG - ya.test - _save_outputs: Command (pid 201795) oublock: 8
2025-11-11 14:47:46,160 - DEBUG - ya.test - _save_outputs: Command (pid 201795) stime: 0.23957699999999998
2025-11-11 14:47:46,160 - DEBUG - ya.test - _save_outputs: Command (pid 201795) utime: 0.131487
2025-11-11 14:47:46,160 - DEBUG - ya.test - _save_outputs: Command (pid 201795) wtime: 0.706
2025-11-11 14:47:46,160 - DEBUG - ya.test - _save_outputs: Command (pid 201795) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

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

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

2025-11-11 14:47:46,161 - DEBUG - ya.test - _save_outputs: Command (pid 201795) errors:

2025-11-11 14:47:46,161 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 8 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 14:47:48,179 - 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-11 14:47:48,181 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 14:47:48,182 - 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-11 14:47:48,184 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-11 14:47:48,184 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f424fcc5820 state=pending>
2025-11-11 14:47:48,184 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-11 14:47:48,185 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,185 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,185 - 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-11 14:47:48,204 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_7
2025-11-11 14:47:48,205 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b63487fd-03f8-4ed1-9511-1e995969cac8, localhost:8880): creating call state
2025-11-11 14:47:48,205 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b63487fd-03f8-4ed1-9511-1e995969cac8, localhost:8880): request = { database: "/Root/users/database_7" }
2025-11-11 14:47:48,213 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b63487fd-03f8-4ed1-9511-1e995969cac8, localhost:8880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 62457 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-11 14:47:48,213 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_7: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:62457, location , ssl: False>]>
2025-11-11 14:47:48,214 - 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-11 14:47:48,247 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:48,247 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,248 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 03bb9b60-a17e-4bc9-93a0-01a96920e1d5, ghrun-l3u76ogmey.auto.internal:62457): creating call state
2025-11-11 14:47:48,249 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 03bb9b60-a17e-4bc9-93a0-01a96920e1d5, ghrun-l3u76ogmey.auto.internal:62457): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:48,257 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 03bb9b60-a17e-4bc9-93a0-01a96920e1d5, ghrun-l3u76ogmey.auto.internal:62457): on response callback started
2025-11-11 14:47:48,258 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 03bb9b60-a17e-4bc9-93a0-01a96920e1d5, ghrun-l3u76ogmey.auto.internal:62457): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50007&id=Y2E4MTNmNzQtYzc4MWRiYzktZmMwY2U0NTUtZTQ5NGFmYmY=" } } } }
2025-11-11 14:47:48,258 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 03bb9b60-a17e-4bc9-93a0-01a96920e1d5, ghrun-l3u76ogmey.auto.internal:62457): on response callback success
2025-11-11 14:47:48,259 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,259 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,259 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 7b34a8e7-5ea1-43c4-bacc-67dddf351310, ghrun-l3u76ogmey.auto.internal:62457): creating call state
2025-11-11 14:47:48,260 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 7b34a8e7-5ea1-43c4-bacc-67dddf351310, ghrun-l3u76ogmey.auto.internal:62457): request = { session_id: "ydb://session/3?node_id=50007&id=Y2E4MTNmNzQtYzc4MWRiYzktZmMwY2U0NTUtZTQ5NGFmYmY=" 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-11 14:47:48,412 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 7b34a8e7-5ea1-43c4-bacc-67dddf351310, ghrun-l3u76ogmey.auto.internal:62457): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:48,413 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,413 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 3febd1a7-79d8-4ee4-8738-71a099e5415d, ghrun-l3u76ogmey.auto.internal:62457): creating call state
2025-11-11 14:47:48,414 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 3febd1a7-79d8-4ee4-8738-71a099e5415d, ghrun-l3u76ogmey.auto.internal:62457): request = { session_id: "ydb://session/3?node_id=50007&id=Y2E4MTNmNzQtYzc4MWRiYzktZmMwY2U0NTUtZTQ5NGFmYmY=" 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-11 14:47:48,475 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 3febd1a7-79d8-4ee4-8738-71a099e5415d, ghrun-l3u76ogmey.auto.internal:62457): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:48,528 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,529 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 30dbafaa-3944-43bf-97bc-b358b4da8704, ghrun-l3u76ogmey.auto.internal:62457): creating call state
2025-11-11 14:47:48,529 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 30dbafaa-3944-43bf-97bc-b358b4da8704, ghrun-l3u76ogmey.auto.internal:62457): request = { session_id: "ydb://session/3?node_id=50007&id=Y2E4MTNmNzQtYzc4MWRiYzktZmMwY2U0NTUtZTQ5NGFmYmY=" 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-11 14:47:48,607 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 30dbafaa-3944-43bf-97bc-b358b4da8704, ghrun-l3u76ogmey.auto.internal:62457): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-11 14:47:48,607 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,608 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 45406e07-6ea8-4ea7-836b-8bc89320038a, ghrun-l3u76ogmey.auto.internal:62457): creating call state
2025-11-11 14:47:48,608 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 45406e07-6ea8-4ea7-836b-8bc89320038a, ghrun-l3u76ogmey.auto.internal:62457): request = { session_id: "ydb://session/3?node_id=50007&id=Y2E4MTNmNzQtYzc4MWRiYzktZmMwY2U0NTUtZTQ5NGFmYmY=" 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-11 14:47:48,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 45406e07-6ea8-4ea7-836b-8bc89320038a, ghrun-l3u76ogmey.auto.internal:62457): 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-11 14:47:48,664 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,665 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 96b99f13-024e-4895-8cc0-7aa93f793a5c, ghrun-l3u76ogmey.auto.internal:62457): creating call state
2025-11-11 14:47:48,665 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 96b99f13-024e-4895-8cc0-7aa93f793a5c, ghrun-l3u76ogmey.auto.internal:62457): request = { session_id: "ydb://session/3?node_id=50007&id=Y2E4MTNmNzQtYzc4MWRiYzktZmMwY2U0NTUtZTQ5NGFmYmY=" path: "/Root/users/database_7/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-11 14:47:48,736 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 96b99f13-024e-4895-8cc0-7aa93f793a5c, ghrun-l3u76ogmey.auto.internal:62457): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:48,737 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,737 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-11 14:47:48,737 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-11 14:47:48,737 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-11 14:47:48,737 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f424fcc6130>, reason: session-pool-terminated
2025-11-11 14:47:48,738 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f424fcc6130> is no longer active. Current active count 0.
2025-11-11 14:47:48,738 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d839f67f-ba7b-4018-af47-964c7af5d70b, ghrun-l3u76ogmey.auto.internal:62457): creating call state
2025-11-11 14:47:48,738 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d839f67f-ba7b-4018-af47-964c7af5d70b, ghrun-l3u76ogmey.auto.internal:62457): request = { session_id: "ydb://session/3?node_id=50007&id=Y2E4MTNmNzQtYzc4MWRiYzktZmMwY2U0NTUtZTQ5NGFmYmY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-11 14:47:48,740 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-11 14:47:48,750 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d839f67f-ba7b-4018-af47-964c7af5d70b, ghrun-l3u76ogmey.auto.internal:62457): on response callback started
2025-11-11 14:47:48,751 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d839f67f-ba7b-4018-af47-964c7af5d70b, ghrun-l3u76ogmey.auto.internal:62457): response = { operation { ready: true status: SUCCESS } }
2025-11-11 14:47:48,751 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d839f67f-ba7b-4018-af47-964c7af5d70b, ghrun-l3u76ogmey.auto.internal:62457): on response callback success
2025-11-11 14:47:48,751 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f424fcc6130>
2025-11-11 14:47:48,751 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-11 14:47:48,752 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:47:48,752 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:47:48,752 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8880
2025-11-11 14:47:48,756 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:62457
2025-11-11 14:47:48,757 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:47:48,757 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_7
2025-11-11 14:47:48,757 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_7: send console operation, token None
2025-11-11 14:47:48,769 - 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=1762872468760332&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-11 14:47:48,770 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_7: wait console operation done
2025-11-11 14:47:49,284 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_7: console operation done
2025-11-11 14:47:49,284 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_7: wait tenant gone
2025-11-11 14:47:49,291 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_7: tenant gone
2025-11-11 14:47:52,295 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:62457/8
2025-11-11 14:47:53,234 - 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-11 14:47:53,237 - INFO - ya.test - pytest_runtest_teardown: Test teardown
