2025-12-04 14:17:27,689 - INFO - ya.test.2836152  - pytest_runtest_setup: ####################################################################################################
2025-12-04 14:17:27,689 - INFO - ya.test.2836152  - pytest_runtest_setup: test_stop_start[enable_alter_database_create_hive_first--true]
2025-12-04 14:17:27,690 - INFO - ya.test.2836152  - pytest_runtest_setup: ####################################################################################################
2025-12-04 14:17:27,691 - INFO - ya.test.2836152  - pytest_runtest_setup: Test setup
2025-12-04 14:17:27,702 - INFO - ydb.tests.library.fixtures.2836152  - ydb_cluster: destroy ydb_cluster for __tests__.test_tenants
2025-12-04 14:17:30,704 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:4287/1
2025-12-04 14:17:30,705 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16721
2025-12-04 14:17:30,705 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24974
2025-12-04 14:17:30,705 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20535
2025-12-04 14:17:30,706 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15561
2025-12-04 14:17:30,706 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23418
2025-12-04 14:17:30,706 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4104
2025-12-04 14:17:30,706 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32479
2025-12-04 14:17:30,706 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10374
2025-12-04 14:17:30,707 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13438
2025-12-04 14:17:30,707 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28114
2025-12-04 14:17:30,707 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11604
2025-12-04 14:17:30,707 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21419
2025-12-04 14:17:30,707 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21899
2025-12-04 14:17:30,708 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17687
2025-12-04 14:17:30,708 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8279
2025-12-04 14:17:30,708 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29055
2025-12-04 14:17:30,708 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23749
2025-12-04 14:17:30,708 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5581
2025-12-04 14:17:30,708 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29347
2025-12-04 14:17:30,708 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12974
2025-12-04 14:17:30,709 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17356
2025-12-04 14:17:30,709 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14100
2025-12-04 14:17:30,709 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63646
2025-12-04 14:17:30,709 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19421
2025-12-04 14:17:30,709 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10753
2025-12-04 14:17:30,709 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4782
2025-12-04 14:17:30,710 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20332
2025-12-04 14:17:30,710 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2417
2025-12-04 14:17:30,710 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4287
2025-12-04 14:17:30,710 - DEBUG - library.python.filelock.2836152  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4097
2025-12-04 14:17:30,780 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:17:30,814 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21427
2025-12-04 14:17:30,837 - INFO - ydb.tests.library.fixtures.2836152  - ydb_cluster: setup ydb_cluster for __tests__.test_tenants
2025-12-04 14:17:30,837 - INFO - ydb.tests.library.fixtures.2836152  - ydb_cluster: setup ydb_cluster as local
2025-12-04 14:17:30,838 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - start: Working directory: /home/runner/.ya/build/build_root/l7pa/002795/r3tmp/kikimr_cluster_zo4qxajz
2025-12-04 14:17:30,894 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:17:30,895 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19973
2025-12-04 14:17:30,895 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1064
2025-12-04 14:17:30,896 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23851
2025-12-04 14:17:30,896 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22873
2025-12-04 14:17:30,897 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:17:30,897 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/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/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_e6m9l146.log --grpc-port=19973 --mon-port=1064 --ic-port=21427
2025-12-04 14:17:30,897 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:19973/1, disk_size 68719476736
2025-12-04 14:17:30,898 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:17:30,898 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/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/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_e6m9l146.log --grpc-port=19973 --mon-port=1064 --ic-port=21427
2025-12-04 14:17:30,899 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_e6m9l146.log', '--grpc-port=19973', '--mon-port=1064', '--ic-port=21427')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-12-04 14:17:30,900 - DEBUG - ya.test.2836152  - execute: Command pid: 2924885
2025-12-04 14:17:30,901 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:19973/1
2025-12-04 14:17:30,902 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.15
2025-12-04 14:17:30,903 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.15
2025-12-04 14:17:30,903 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:17:30,904 - DEBUG - ya.test.2836152  - execute: Command pid: 2924886
2025-12-04 14:17:31,589 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) rc: 0
2025-12-04 14:17:31,589 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) elapsed time (sec): 0.6842834949493408
2025-12-04 14:17:31,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) idrss: 0
2025-12-04 14:17:31,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) inblock: 0
2025-12-04 14:17:31,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) isrss: 0
2025-12-04 14:17:31,599 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) ixrss: 0
2025-12-04 14:17:31,600 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) majflt: 0
2025-12-04 14:17:31,601 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) maxrss: 546756
2025-12-04 14:17:31,601 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) minflt: 16259
2025-12-04 14:17:31,601 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) msgrcv: 0
2025-12-04 14:17:31,601 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) msgsnd: 0
2025-12-04 14:17:31,601 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) nivcsw: 130
2025-12-04 14:17:31,602 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) nsignals: 0
2025-12-04 14:17:31,602 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) nswap: 0
2025-12-04 14:17:31,602 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) nvcsw: 16
2025-12-04 14:17:31,602 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) oublock: 8
2025-12-04 14:17:31,602 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) stime: 0.322432
2025-12-04 14:17:31,603 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) utime: 0.122815
2025-12-04 14:17:31,603 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) wtime: 0.685
2025-12-04 14:17:31,603 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:17:31,603 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2924886) errors:

2025-12-04 14:17:31,603 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 1 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:17:33,544 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:17:33,545 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19973', '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: 21427\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-04 14:17:33,550 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.16
2025-12-04 14:17:33,561 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.16
2025-12-04 14:17:33,561 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19973', '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: 21427\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 14:17:33,563 - DEBUG - ya.test.2836152  - execute: Command pid: 2925977
2025-12-04 14:17:34,175 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) rc: 0
2025-12-04 14:17:34,181 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) elapsed time (sec): 0.6115355491638184
2025-12-04 14:17:34,182 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) idrss: 0
2025-12-04 14:17:34,182 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) inblock: 0
2025-12-04 14:17:34,189 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) isrss: 0
2025-12-04 14:17:34,189 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) ixrss: 0
2025-12-04 14:17:34,190 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) majflt: 0
2025-12-04 14:17:34,190 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) maxrss: 555264
2025-12-04 14:17:34,190 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) minflt: 18400
2025-12-04 14:17:34,190 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) msgrcv: 0
2025-12-04 14:17:34,191 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) msgsnd: 0
2025-12-04 14:17:34,197 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) nivcsw: 151
2025-12-04 14:17:34,198 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) nsignals: 0
2025-12-04 14:17:34,198 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) nswap: 0
2025-12-04 14:17:34,198 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) nvcsw: 120
2025-12-04 14:17:34,198 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) oublock: 8
2025-12-04 14:17:34,198 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) stime: 0.36910099999999996
2025-12-04 14:17:34,199 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) utime: 0.13350399999999998
2025-12-04 14:17:34,199 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) wtime: 0.613
2025-12-04 14:17:34,199 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-04 14:17:34,199 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2925977) errors:

2025-12-04 14:17:34,200 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:17:34,209 - DEBUG - ydb.tests.library.harness.kikimr_runner.2836152  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19973', '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-12-04 14:17:34,210 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.17
2025-12-04 14:17:34,219 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.17
2025-12-04 14:17:34,224 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19973', '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-12-04 14:17:34,235 - DEBUG - ya.test.2836152  - execute: Command pid: 2926322
2025-12-04 14:17:35,020 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) rc: 0
2025-12-04 14:17:35,020 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) elapsed time (sec): 0.7808043956756592
2025-12-04 14:17:35,020 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) idrss: 0
2025-12-04 14:17:35,021 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) inblock: 0
2025-12-04 14:17:35,021 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) isrss: 0
2025-12-04 14:17:35,021 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) ixrss: 0
2025-12-04 14:17:35,021 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) majflt: 0
2025-12-04 14:17:35,021 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) maxrss: 555260
2025-12-04 14:17:35,021 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) minflt: 18401
2025-12-04 14:17:35,022 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) msgrcv: 0
2025-12-04 14:17:35,022 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) msgsnd: 0
2025-12-04 14:17:35,022 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) nivcsw: 180
2025-12-04 14:17:35,022 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) nsignals: 0
2025-12-04 14:17:35,022 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) nswap: 0
2025-12-04 14:17:35,022 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) nvcsw: 117
2025-12-04 14:17:35,023 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) oublock: 8
2025-12-04 14:17:35,023 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) stime: 0.392644
2025-12-04 14:17:35,023 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) utime: 0.14510699999999999
2025-12-04 14:17:35,023 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) wtime: 0.795
2025-12-04 14:17:35,024 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-04 14:17:35,024 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2926322) errors:

2025-12-04 14:17:35,025 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-04 14:17:35,039 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __run: Cluster started and initialized
2025-12-04 14:17:35,072 - INFO - ya.test.2836152  - pytest_runtest_call: Test call (class_name: test_tenants.py::TestTenants, test_name: test_stop_start[enable_alter_database_create_hive_first--true])
2025-12-04 14:17:35,081 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:17:35,081 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:17:35,085 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
}

2025-12-04 14:17:35,087 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:17:35,599 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:17:35,599 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:17:35,607 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:17:35,608 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-04 14:17:36,153 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-04 14:17:36,153 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:17:36,154 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15558
2025-12-04 14:17:36,177 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7027
2025-12-04 14:17:36,177 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24822
2025-12-04 14:17:36,178 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14201
2025-12-04 14:17:36,178 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12438
2025-12-04 14:17:36,179 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:17:36,179 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_7kd31vx0.log --grpc-port=15558 --mon-port=7027 --ic-port=24822
2025-12-04 14:17:36,182 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:17:36,184 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_7kd31vx0.log --grpc-port=15558 --mon-port=7027 --ic-port=24822
2025-12-04 14:17:36,186 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:19973', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_7kd31vx0.log', '--grpc-port=15558', '--mon-port=7027', '--ic-port=24822')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-12-04 14:17:36,205 - DEBUG - ya.test.2836152  - execute: Command pid: 2927819
2025-12-04 14:17:36,205 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:15558/1
2025-12-04 14:17:36,207 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.18
2025-12-04 14:17:36,208 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.18
2025-12-04 14:17:36,208 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:17:36,213 - DEBUG - ya.test.2836152  - execute: Command pid: 2927855
2025-12-04 14:17:37,210 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) rc: 0
2025-12-04 14:17:37,211 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) elapsed time (sec): 0.9960558414459229
2025-12-04 14:17:37,211 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) idrss: 0
2025-12-04 14:17:37,211 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) inblock: 0
2025-12-04 14:17:37,212 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) isrss: 0
2025-12-04 14:17:37,212 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) ixrss: 0
2025-12-04 14:17:37,212 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) majflt: 0
2025-12-04 14:17:37,212 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) maxrss: 546756
2025-12-04 14:17:37,212 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) minflt: 16404
2025-12-04 14:17:37,213 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) msgrcv: 0
2025-12-04 14:17:37,213 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) msgsnd: 0
2025-12-04 14:17:37,213 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) nivcsw: 163
2025-12-04 14:17:37,213 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) nsignals: 0
2025-12-04 14:17:37,215 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) nswap: 0
2025-12-04 14:17:37,216 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) nvcsw: 22
2025-12-04 14:17:37,216 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) oublock: 8
2025-12-04 14:17:37,216 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) stime: 0.5141169999999999
2025-12-04 14:17:37,216 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) utime: 0.127627
2025-12-04 14:17:37,216 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) wtime: 1.002
2025-12-04 14:17:37,216 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:17:37,217 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2927855) errors:

2025-12-04 14:17:37,217 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 1 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:17:37,217 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-04 14:17:43,251 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-04 14:17:43,256 - DEBUG - ydb.connection.2836152  - 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-12-04 14:17:43,272 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:17:43,272 - DEBUG - ydb.connection.2836152  - 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-12-04 14:17:43,287 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:17:43,287 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe704fce270 state=pending>
2025-12-04 14:17:43,287 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:17:43,288 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe704fcf300>
2025-12-04 14:17:43,288 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704fcf300>
2025-12-04 14:17:43,288 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:17:43,304 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:17:43,346 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 7e7a5ab4-15a4-4e87-8211-4385245f185a, localhost:19973): creating call state
2025-12-04 14:17:43,346 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 7e7a5ab4-15a4-4e87-8211-4385245f185a, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:17:43,351 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 7e7a5ab4-15a4-4e87-8211-4385245f185a, localhost:19973): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 15558 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-12-04 14:17:43,352 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:15558, location , ssl: False>]>
2025-12-04 14:17:43,352 - DEBUG - ydb.connection.2836152  - 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-12-04 14:17:43,363 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:17:43,364 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704fcf300>
2025-12-04 14:17:43,365 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, ba4b91b5-3adf-43c4-a85a-4aa299dcba7c, ghrun-e3snp7fbzi.auto.internal:15558): creating call state
2025-12-04 14:17:43,365 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, ba4b91b5-3adf-43c4-a85a-4aa299dcba7c, ghrun-e3snp7fbzi.auto.internal:15558): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:17:43,368 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, ba4b91b5-3adf-43c4-a85a-4aa299dcba7c, ghrun-e3snp7fbzi.auto.internal:15558): on response callback started
2025-12-04 14:17:43,377 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, ba4b91b5-3adf-43c4-a85a-4aa299dcba7c, ghrun-e3snp7fbzi.auto.internal:15558): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=MzU1OTkzNWUtNGVjZjkzZGYtY2QwMTM2ZDYtYWRkYjRkMDQ=" } } } }
2025-12-04 14:17:43,377 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704fcf300>
2025-12-04 14:17:43,378 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe704fcf300>
2025-12-04 14:17:43,378 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, ba4b91b5-3adf-43c4-a85a-4aa299dcba7c, ghrun-e3snp7fbzi.auto.internal:15558): on response callback success
2025-12-04 14:17:43,379 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ExecuteSchemeQuery, 40e22ee6-e775-4168-a62b-fb1fe37402e1, ghrun-e3snp7fbzi.auto.internal:15558): creating call state
2025-12-04 14:17:43,381 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ExecuteSchemeQuery, 40e22ee6-e775-4168-a62b-fb1fe37402e1, ghrun-e3snp7fbzi.auto.internal:15558): request = { session_id: "ydb://session/3?node_id=50000&id=MzU1OTkzNWUtNGVjZjkzZGYtY2QwMTM2ZDYtYWRkYjRkMDQ=" yql_text: "\n                CREATE TABLE `table_0` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:17:43,451 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ExecuteSchemeQuery, 40e22ee6-e775-4168-a62b-fb1fe37402e1, ghrun-e3snp7fbzi.auto.internal:15558): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:17:43,479 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704fcf300>
2025-12-04 14:17:43,479 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-04 14:17:43,480 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:17:43,480 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:17:43,488 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_&cmstid=72057594037936131&txid=1764857863483382&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:17:43,493 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:17:44,005 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:17:44,007 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:17:44,012 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:17:44,015 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-04 14:17:56,017 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:15558/1
2025-12-04 14:17:56,017 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-04 14:17:56,018 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:17:56,018 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:17:56,022 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
}

2025-12-04 14:17:56,022 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:17:56,526 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:17:56,526 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:17:56,533 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:17:56,533 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-04 14:17:57,053 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-04 14:17:57,057 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:17:57,057 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15900
2025-12-04 14:17:57,058 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22881
2025-12-04 14:17:57,058 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23223
2025-12-04 14:17:57,058 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25301
2025-12-04 14:17:57,059 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22644
2025-12-04 14:17:57,059 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:17:57,060 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_o502osgw.log --grpc-port=15900 --mon-port=22881 --ic-port=23223
2025-12-04 14:17:57,061 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:17:57,061 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_o502osgw.log --grpc-port=15900 --mon-port=22881 --ic-port=23223
2025-12-04 14:17:57,062 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:19973', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_o502osgw.log', '--grpc-port=15900', '--mon-port=22881', '--ic-port=23223')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-12-04 14:17:57,072 - DEBUG - ya.test.2836152  - execute: Command pid: 2939245
2025-12-04 14:17:57,073 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:15900/2
2025-12-04 14:17:57,075 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.19
2025-12-04 14:17:57,075 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.19
2025-12-04 14:17:57,076 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:17:57,077 - DEBUG - ya.test.2836152  - execute: Command pid: 2939268
2025-12-04 14:17:57,589 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) rc: 0
2025-12-04 14:17:57,589 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) elapsed time (sec): 0.4998931884765625
2025-12-04 14:17:57,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) idrss: 0
2025-12-04 14:17:57,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) inblock: 0
2025-12-04 14:17:57,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) isrss: 0
2025-12-04 14:17:57,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) ixrss: 0
2025-12-04 14:17:57,590 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) majflt: 0
2025-12-04 14:17:57,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) maxrss: 546756
2025-12-04 14:17:57,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) minflt: 16258
2025-12-04 14:17:57,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) msgrcv: 0
2025-12-04 14:17:57,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) msgsnd: 0
2025-12-04 14:17:57,591 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) nivcsw: 119
2025-12-04 14:17:57,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) nsignals: 0
2025-12-04 14:17:57,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) nswap: 0
2025-12-04 14:17:57,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) nvcsw: 16
2025-12-04 14:17:57,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) oublock: 8
2025-12-04 14:17:57,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) stime: 0.26264
2025-12-04 14:17:57,592 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) utime: 0.119074
2025-12-04 14:17:57,593 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) wtime: 0.513
2025-12-04 14:17:57,593 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:17:57,598 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2939268) errors:

2025-12-04 14:17:57,598 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 2 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:17:57,599 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-04 14:17:59,613 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-04 14:17:59,614 - DEBUG - ydb.connection.2836152  - 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-12-04 14:17:59,628 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:17:59,634 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:17:59,671 - DEBUG - ydb.connection.2836152  - 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-12-04 14:17:59,674 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe6f07f8ba0 state=pending>
2025-12-04 14:17:59,686 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:17:59,687 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe706b95460>
2025-12-04 14:17:59,687 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe706b95460>
2025-12-04 14:17:59,687 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:17:59,692 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:17:59,716 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 9cd64e78-bf6d-4840-a0a5-3b03380d3ba3, localhost:19973): creating call state
2025-12-04 14:17:59,717 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 9cd64e78-bf6d-4840-a0a5-3b03380d3ba3, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:17:59,720 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 9cd64e78-bf6d-4840-a0a5-3b03380d3ba3, localhost:19973): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 15900 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-12-04 14:17:59,721 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:15900, location , ssl: False>]>
2025-12-04 14:17:59,721 - DEBUG - ydb.connection.2836152  - 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-12-04 14:17:59,740 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:17:59,740 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe706b95460>
2025-12-04 14:17:59,741 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, 9480b916-182e-4e56-bb9b-96b853e8e599, ghrun-e3snp7fbzi.auto.internal:15900): creating call state
2025-12-04 14:17:59,742 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, 9480b916-182e-4e56-bb9b-96b853e8e599, ghrun-e3snp7fbzi.auto.internal:15900): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:17:59,745 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 9480b916-182e-4e56-bb9b-96b853e8e599, ghrun-e3snp7fbzi.auto.internal:15900): on response callback started
2025-12-04 14:17:59,746 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, 9480b916-182e-4e56-bb9b-96b853e8e599, ghrun-e3snp7fbzi.auto.internal:15900): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=MzFkMTQwMzMtYWM0NjNhZWEtYjdiYWNlZWItM2I2MmI2MTc=" } } } }
2025-12-04 14:17:59,746 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe706b95460>
2025-12-04 14:17:59,757 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe706b95460>
2025-12-04 14:17:59,757 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 9480b916-182e-4e56-bb9b-96b853e8e599, ghrun-e3snp7fbzi.auto.internal:15900): on response callback success
2025-12-04 14:17:59,758 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ExecuteSchemeQuery, b98d778f-24cc-4076-bbca-dc96bd1e63b1, ghrun-e3snp7fbzi.auto.internal:15900): creating call state
2025-12-04 14:17:59,775 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ExecuteSchemeQuery, b98d778f-24cc-4076-bbca-dc96bd1e63b1, ghrun-e3snp7fbzi.auto.internal:15900): request = { session_id: "ydb://session/3?node_id=50001&id=MzFkMTQwMzMtYWM0NjNhZWEtYjdiYWNlZWItM2I2MmI2MTc=" yql_text: "\n                CREATE TABLE `table_1` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:17:59,886 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ExecuteSchemeQuery, b98d778f-24cc-4076-bbca-dc96bd1e63b1, ghrun-e3snp7fbzi.auto.internal:15900): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:17:59,886 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe706b95460>
2025-12-04 14:17:59,888 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-04 14:17:59,888 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:17:59,889 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:17:59,900 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_&cmstid=72057594037936131&txid=1764857879892994&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:17:59,901 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:18:00,411 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:18:00,469 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:18:00,478 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:18:00,497 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-04 14:18:22,503 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:15900/2
2025-12-04 14:18:22,503 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-04 14:18:22,504 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:22,505 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:18:22,510 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
}

2025-12-04 14:18:22,511 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:18:23,017 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:18:23,017 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:18:23,020 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:18:23,020 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-04 14:18:23,533 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-04 14:18:23,534 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:18:23,534 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4093
2025-12-04 14:18:23,535 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8399
2025-12-04 14:18:23,535 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5035
2025-12-04 14:18:23,536 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5036
2025-12-04 14:18:23,536 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9384
2025-12-04 14:18:23,536 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20456
2025-12-04 14:18:23,542 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:23,543 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_3y5k7pbh.log --grpc-port=4093 --mon-port=8399 --ic-port=5036
2025-12-04 14:18:23,544 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:23,544 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_3y5k7pbh.log --grpc-port=4093 --mon-port=8399 --ic-port=5036
2025-12-04 14:18:23,545 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:19973', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_3y5k7pbh.log', '--grpc-port=4093', '--mon-port=8399', '--ic-port=5036')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-12-04 14:18:23,548 - DEBUG - ya.test.2836152  - execute: Command pid: 2948505
2025-12-04 14:18:23,548 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:4093/3
2025-12-04 14:18:23,550 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.20
2025-12-04 14:18:23,550 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.20
2025-12-04 14:18:23,559 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:18:23,563 - DEBUG - ya.test.2836152  - execute: Command pid: 2948515
2025-12-04 14:18:24,164 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) rc: 0
2025-12-04 14:18:24,165 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) elapsed time (sec): 0.5829024314880371
2025-12-04 14:18:24,165 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) idrss: 0
2025-12-04 14:18:24,165 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) inblock: 0
2025-12-04 14:18:24,165 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) isrss: 0
2025-12-04 14:18:24,166 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) ixrss: 0
2025-12-04 14:18:24,166 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) majflt: 0
2025-12-04 14:18:24,166 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) maxrss: 546756
2025-12-04 14:18:24,166 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) minflt: 16261
2025-12-04 14:18:24,173 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) msgrcv: 0
2025-12-04 14:18:24,186 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) msgsnd: 0
2025-12-04 14:18:24,186 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) nivcsw: 123
2025-12-04 14:18:24,186 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) nsignals: 0
2025-12-04 14:18:24,186 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) nswap: 0
2025-12-04 14:18:24,186 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) nvcsw: 20
2025-12-04 14:18:24,187 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) oublock: 8
2025-12-04 14:18:24,187 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) stime: 0.29973099999999997
2025-12-04 14:18:24,187 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) utime: 0.12234199999999999
2025-12-04 14:18:24,187 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) wtime: 0.587
2025-12-04 14:18:24,187 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:18:24,188 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2948515) errors:

2025-12-04 14:18:24,188 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 3 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:18:24,188 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-04 14:18:26,199 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-04 14:18:26,200 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:26,202 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:26,202 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:26,211 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:18:26,212 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe704d99a50 state=pending>
2025-12-04 14:18:26,212 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:18:26,212 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe704d985b0>
2025-12-04 14:18:26,212 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704d985b0>
2025-12-04 14:18:26,213 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:18:26,213 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:26,214 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, b44b1630-a97f-48ce-8b14-35bf48e3f068, localhost:19973): creating call state
2025-12-04 14:18:26,215 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, b44b1630-a97f-48ce-8b14-35bf48e3f068, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:18:26,217 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, b44b1630-a97f-48ce-8b14-35bf48e3f068, localhost:19973): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 4093 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-12-04 14:18:26,220 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:4093, location , ssl: False>]>
2025-12-04 14:18:26,220 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:26,230 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:18:26,231 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe704d985b0>
2025-12-04 14:18:26,232 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, bbe64427-2dae-4efb-b51e-fdf449b69aef, ghrun-e3snp7fbzi.auto.internal:4093): creating call state
2025-12-04 14:18:26,232 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, bbe64427-2dae-4efb-b51e-fdf449b69aef, ghrun-e3snp7fbzi.auto.internal:4093): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:26,236 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, bbe64427-2dae-4efb-b51e-fdf449b69aef, ghrun-e3snp7fbzi.auto.internal:4093): on response callback started
2025-12-04 14:18:26,237 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, bbe64427-2dae-4efb-b51e-fdf449b69aef, ghrun-e3snp7fbzi.auto.internal:4093): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=MzE0NjA1NjItNDE1NzIwNDktYjhmNDY4OTgtNDJmMTgzYjc=" } } } }
2025-12-04 14:18:26,237 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704d985b0>
2025-12-04 14:18:26,237 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe704d985b0>
2025-12-04 14:18:26,239 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, bbe64427-2dae-4efb-b51e-fdf449b69aef, ghrun-e3snp7fbzi.auto.internal:4093): on response callback success
2025-12-04 14:18:26,238 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ExecuteSchemeQuery, 29cfde09-600d-449a-8838-53045916c584, ghrun-e3snp7fbzi.auto.internal:4093): creating call state
2025-12-04 14:18:26,243 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ExecuteSchemeQuery, 29cfde09-600d-449a-8838-53045916c584, ghrun-e3snp7fbzi.auto.internal:4093): request = { session_id: "ydb://session/3?node_id=50002&id=MzE0NjA1NjItNDE1NzIwNDktYjhmNDY4OTgtNDJmMTgzYjc=" yql_text: "\n                CREATE TABLE `table_2` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:18:26,332 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ExecuteSchemeQuery, 29cfde09-600d-449a-8838-53045916c584, ghrun-e3snp7fbzi.auto.internal:4093): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:26,335 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe704d985b0>
2025-12-04 14:18:26,336 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-04 14:18:26,338 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:26,339 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:18:26,346 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_&cmstid=72057594037936131&txid=1764857906341896&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:18:26,347 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:18:26,850 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:18:26,851 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:18:26,854 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:18:26,870 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-04 14:18:48,879 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:4093/3
2025-12-04 14:18:48,883 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-04 14:18:48,884 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:48,884 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:18:48,891 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
}

2025-12-04 14:18:48,891 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:18:49,395 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:18:49,396 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:18:49,402 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:18:49,403 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-04 14:18:49,921 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-04 14:18:49,922 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:18:49,922 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62724
2025-12-04 14:18:49,923 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2714
2025-12-04 14:18:49,926 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7200
2025-12-04 14:18:49,927 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20204
2025-12-04 14:18:49,927 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10064
2025-12-04 14:18:49,928 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:49,928 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_ygmpoigw.log --grpc-port=62724 --mon-port=2714 --ic-port=7200
2025-12-04 14:18:49,928 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:18:49,928 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_ygmpoigw.log --grpc-port=62724 --mon-port=2714 --ic-port=7200
2025-12-04 14:18:49,929 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:19973', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_ygmpoigw.log', '--grpc-port=62724', '--mon-port=2714', '--ic-port=7200')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_4' (no wait)
2025-12-04 14:18:49,937 - DEBUG - ya.test.2836152  - execute: Command pid: 2960895
2025-12-04 14:18:49,937 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:62724/4
2025-12-04 14:18:49,939 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.21
2025-12-04 14:18:49,940 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.21
2025-12-04 14:18:49,940 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:18:49,949 - DEBUG - ya.test.2836152  - execute: Command pid: 2960896
2025-12-04 14:18:50,689 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) rc: 0
2025-12-04 14:18:50,689 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) elapsed time (sec): 0.7387995719909668
2025-12-04 14:18:50,689 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) idrss: 0
2025-12-04 14:18:50,689 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) inblock: 0
2025-12-04 14:18:50,690 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) isrss: 0
2025-12-04 14:18:50,690 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) ixrss: 0
2025-12-04 14:18:50,690 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) majflt: 0
2025-12-04 14:18:50,690 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) maxrss: 546764
2025-12-04 14:18:50,690 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) minflt: 16259
2025-12-04 14:18:50,690 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) msgrcv: 0
2025-12-04 14:18:50,691 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) msgsnd: 0
2025-12-04 14:18:50,691 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) nivcsw: 134
2025-12-04 14:18:50,691 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) nsignals: 0
2025-12-04 14:18:50,691 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) nswap: 0
2025-12-04 14:18:50,691 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) nvcsw: 14
2025-12-04 14:18:50,692 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) oublock: 8
2025-12-04 14:18:50,692 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) stime: 0.310195
2025-12-04 14:18:50,692 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) utime: 0.114895
2025-12-04 14:18:50,692 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) wtime: 0.748
2025-12-04 14:18:50,692 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:18:50,692 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2960896) errors:

2025-12-04 14:18:50,693 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 4 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:18:50,693 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-04 14:18:52,722 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-04 14:18:52,756 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:52,763 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:52,768 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:52,781 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:18:52,813 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe70538f1c0 state=pending>
2025-12-04 14:18:52,813 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:18:52,813 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe70538da50>
2025-12-04 14:18:52,814 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe70538da50>
2025-12-04 14:18:52,814 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:18:52,798 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:52,815 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 608657cf-c39d-402d-93ef-f1655fcca4cf, localhost:19973): creating call state
2025-12-04 14:18:52,815 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 608657cf-c39d-402d-93ef-f1655fcca4cf, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:18:52,835 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 608657cf-c39d-402d-93ef-f1655fcca4cf, localhost:19973): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 62724 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-12-04 14:18:52,835 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:62724, location , ssl: False>]>
2025-12-04 14:18:52,836 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:52,870 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:18:52,875 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe70538da50>
2025-12-04 14:18:52,881 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, 2933f096-232a-4001-a143-3da4881da5f9, ghrun-e3snp7fbzi.auto.internal:62724): creating call state
2025-12-04 14:18:52,882 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, 2933f096-232a-4001-a143-3da4881da5f9, ghrun-e3snp7fbzi.auto.internal:62724): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:18:52,893 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 2933f096-232a-4001-a143-3da4881da5f9, ghrun-e3snp7fbzi.auto.internal:62724): on response callback started
2025-12-04 14:18:52,893 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, 2933f096-232a-4001-a143-3da4881da5f9, ghrun-e3snp7fbzi.auto.internal:62724): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=OWU1YTM1NGItMWMyMzlhNGQtNjgwMGQyNzMtOWVmMGI5ZA==" } } } }
2025-12-04 14:18:52,894 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 2933f096-232a-4001-a143-3da4881da5f9, ghrun-e3snp7fbzi.auto.internal:62724): on response callback success
2025-12-04 14:18:52,894 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe70538da50>
2025-12-04 14:18:52,894 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe70538da50>
2025-12-04 14:18:52,895 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ExecuteSchemeQuery, b57623d0-c051-4fc0-b810-0bd5fa8e324e, ghrun-e3snp7fbzi.auto.internal:62724): creating call state
2025-12-04 14:18:52,896 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ExecuteSchemeQuery, b57623d0-c051-4fc0-b810-0bd5fa8e324e, ghrun-e3snp7fbzi.auto.internal:62724): request = { session_id: "ydb://session/3?node_id=50003&id=OWU1YTM1NGItMWMyMzlhNGQtNjgwMGQyNzMtOWVmMGI5ZA==" yql_text: "\n                CREATE TABLE `table_3` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:18:52,995 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ExecuteSchemeQuery, b57623d0-c051-4fc0-b810-0bd5fa8e324e, ghrun-e3snp7fbzi.auto.internal:62724): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:18:52,996 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe70538da50>
2025-12-04 14:18:52,996 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-04 14:18:52,997 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:52,997 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:18:53,011 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_&cmstid=72057594037936131&txid=1764857933000701&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:18:53,012 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:18:53,519 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:18:53,519 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:18:53,522 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:18:53,523 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-04 14:18:57,524 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:57,525 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:57,540 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:57,540 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 789491a4-cad0-4a3d-996f-6ac370f0f4ed, localhost:19973): creating call state
2025-12-04 14:18:57,540 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 789491a4-cad0-4a3d-996f-6ac370f0f4ed, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:18:57,546 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 789491a4-cad0-4a3d-996f-6ac370f0f4ed, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:18:57,547 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:18:57,547 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:18:59,546 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:18:59,547 - DEBUG - ydb.connection.2836152  - 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-12-04 14:18:59,567 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:18:59,577 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 31235411-042b-445b-b974-da88282e8909, localhost:19973): creating call state
2025-12-04 14:18:59,577 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 31235411-042b-445b-b974-da88282e8909, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:18:59,615 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 31235411-042b-445b-b974-da88282e8909, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:18:59,616 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:18:59,632 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:00,846 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:00,847 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:00,875 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:00,876 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, d7aa1f80-cf0e-4c99-9463-58919edf3f38, localhost:19973): creating call state
2025-12-04 14:19:00,876 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, d7aa1f80-cf0e-4c99-9463-58919edf3f38, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:00,881 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, d7aa1f80-cf0e-4c99-9463-58919edf3f38, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:00,882 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:00,882 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:02,424 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:02,428 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:02,462 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:02,463 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 27d79895-ddd8-4ef4-9503-ab19c52cd288, localhost:19973): creating call state
2025-12-04 14:19:02,463 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 27d79895-ddd8-4ef4-9503-ab19c52cd288, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:02,469 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 27d79895-ddd8-4ef4-9503-ab19c52cd288, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:02,478 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:02,478 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:04,027 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:04,051 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:04,071 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:04,079 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 1c356138-f4c4-4468-8159-752af24dd085, localhost:19973): creating call state
2025-12-04 14:19:04,079 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 1c356138-f4c4-4468-8159-752af24dd085, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:04,086 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 1c356138-f4c4-4468-8159-752af24dd085, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:04,087 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:04,087 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:05,531 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:62724/4
2025-12-04 14:19:05,535 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-04 14:19:05,539 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:05,547 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:19:05,560 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/test_stop_start_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
}

2025-12-04 14:19:05,597 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:19:05,628 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:05,630 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:05,639 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:05,639 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, a90c5c83-b1e8-459a-8a74-8e8aecc928eb, localhost:19973): creating call state
2025-12-04 14:19:05,640 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, a90c5c83-b1e8-459a-8a74-8e8aecc928eb, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:05,645 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, a90c5c83-b1e8-459a-8a74-8e8aecc928eb, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:05,645 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:05,646 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:06,101 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:19:06,101 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:19:06,104 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:19:06,105 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-04 14:19:06,622 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-04 14:19:06,623 - DEBUG - ya.test.2836152  - get_binary: Binary was found by /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd
2025-12-04 14:19:06,623 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10351
2025-12-04 14:19:06,623 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3538
2025-12-04 14:19:06,624 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10092
2025-12-04 14:19:06,624 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15503
2025-12-04 14:19:06,635 - DEBUG - library.python.filelock.2836152  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12055
2025-12-04 14:19:06,636 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:19:06,637 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_areb_hqa.log --grpc-port=10351 --mon-port=3538 --ic-port=10092
2025-12-04 14:19:06,638 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 14:19:06,638 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - __make_run_command: Final command: /home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:19973 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_areb_hqa.log --grpc-port=10351 --mon-port=3538 --ic-port=10092
2025-12-04 14:19:06,648 - DEBUG - ya.test.2836152  - execute: Executing '('/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:19973', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_areb_hqa.log', '--grpc-port=10351', '--mon-port=3538', '--ic-port=10092')' in '/home/runner/.ya/build/build_root/l7pa/002795/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk12/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_5' (no wait)
2025-12-04 14:19:06,670 - DEBUG - ya.test.2836152  - execute: Command pid: 2970536
2025-12-04 14:19:06,671 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Started node localhost:10351/5
2025-12-04 14:19:06,672 - DEBUG - ya.test.2836152  - _get_command_output_file: Command out will be placed to ydbd.out.22
2025-12-04 14:19:06,673 - DEBUG - ya.test.2836152  - _get_command_output_file: Command err will be placed to ydbd.err.22
2025-12-04 14:19:06,674 - DEBUG - ya.test.2836152  - execute: Executing '['/home/runner/.ya/build/build_root/l7pa/002795/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 14:19:06,690 - DEBUG - ya.test.2836152  - execute: Command pid: 2970558
2025-12-04 14:19:07,395 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) rc: 0
2025-12-04 14:19:07,395 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) elapsed time (sec): 0.7035226821899414
2025-12-04 14:19:07,396 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) idrss: 0
2025-12-04 14:19:07,396 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) inblock: 0
2025-12-04 14:19:07,400 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) isrss: 0
2025-12-04 14:19:07,400 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) ixrss: 0
2025-12-04 14:19:07,400 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) majflt: 0
2025-12-04 14:19:07,401 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) maxrss: 546756
2025-12-04 14:19:07,401 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) minflt: 16263
2025-12-04 14:19:07,405 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) msgrcv: 0
2025-12-04 14:19:07,405 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) msgsnd: 0
2025-12-04 14:19:07,405 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) nivcsw: 128
2025-12-04 14:19:07,406 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) nsignals: 0
2025-12-04 14:19:07,406 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) nswap: 0
2025-12-04 14:19:07,406 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) nvcsw: 18
2025-12-04 14:19:07,407 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) oublock: 8
2025-12-04 14:19:07,407 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) stime: 0.264814
2025-12-04 14:19:07,407 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) utime: 0.136261
2025-12-04 14:19:07,408 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) wtime: 0.721
2025-12-04 14:19:07,408 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) output:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-04 14:19:07,408 - DEBUG - ya.test.2836152  - _save_outputs: Command (pid 2970558) errors:

2025-12-04 14:19:07,408 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - start: Node 5 version:
Git info:
    Commit: ca9c15393d22c12db97ca6ccdce9f8ddd631707e
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge f3dec252c11d69ae9fba7b459be5780aba4090e2 into 40899112e9a669d2457765c18fa052a89f267bb6
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-e3snp7fbzi    Host information: 
        Linux ghrun-e3snp7fbzi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 14:19:07,408 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-04 14:19:07,664 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:07,665 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:07,695 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:07,695 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 62d85dc3-7561-404b-9ed3-f73698c31d14, localhost:19973): creating call state
2025-12-04 14:19:07,699 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 62d85dc3-7561-404b-9ed3-f73698c31d14, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:07,733 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 62d85dc3-7561-404b-9ed3-f73698c31d14, localhost:19973): response = { operation { ready: true status: UNAVAILABLE issues { message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 } } }
2025-12-04 14:19:07,734 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Database nodes resolve failed with no certain result" issue_code: 200400 severity: 1 (server_code: 400050)
2025-12-04 14:19:07,737 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:09,427 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-04 14:19:09,428 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:09,429 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:09,430 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:09,433 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 14:19:09,433 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Subscribe waiter <Future at 0x7fe7053724a0 state=pending>
2025-12-04 14:19:09,434 - DEBUG - ydb.table.SessionPool.2836152  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 14:19:09,436 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:09,440 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 706fd9c2-ab32-4a6c-9b37-7793e9a4697b, localhost:19973): creating call state
2025-12-04 14:19:09,438 - DEBUG - ydb.table.SessionPool.2836152  - _create: Created session <ydb.table.Session object at 0x7fe705372db0>
2025-12-04 14:19:09,442 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 706fd9c2-ab32-4a6c-9b37-7793e9a4697b, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:09,443 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe705372db0>
2025-12-04 14:19:09,444 - ERROR - ydb.table.SessionPool.2836152  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 14:19:09,447 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 706fd9c2-ab32-4a6c-9b37-7793e9a4697b, localhost:19973): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 10351 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-12-04 14:19:09,480 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:10351, location , ssl: False>]>
2025-12-04 14:19:09,483 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:09,547 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:09,547 - DEBUG - ydb.table.SessionPool.2836152  - _prepare: Preparing session <ydb.table.Session object at 0x7fe705372db0>
2025-12-04 14:19:09,631 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(CreateSession, 6d840780-2fd8-4b59-82c0-55e838d1db31, ghrun-e3snp7fbzi.auto.internal:10351): creating call state
2025-12-04 14:19:09,632 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(CreateSession, 6d840780-2fd8-4b59-82c0-55e838d1db31, ghrun-e3snp7fbzi.auto.internal:10351): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:09,672 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 6d840780-2fd8-4b59-82c0-55e838d1db31, ghrun-e3snp7fbzi.auto.internal:10351): on response callback started
2025-12-04 14:19:09,710 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(CreateSession, 6d840780-2fd8-4b59-82c0-55e838d1db31, ghrun-e3snp7fbzi.auto.internal:10351): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50004&id=ZWU4ZjlmMTMtNDYxM2M1N2QtMjcyNWE0YTItNGE2YWJjOTY=" } } } }
2025-12-04 14:19:09,714 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:09,748 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe705372db0>
2025-12-04 14:19:09,755 - DEBUG - ydb.table.SessionPool.2836152  - put: Replying to waiter with a session <ydb.table.Session object at 0x7fe705372db0>
2025-12-04 14:19:09,756 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(CreateSession, 6d840780-2fd8-4b59-82c0-55e838d1db31, ghrun-e3snp7fbzi.auto.internal:10351): on response callback success
2025-12-04 14:19:09,753 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:09,755 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ExecuteSchemeQuery, 44b856af-0f29-4d3a-b754-aa3507ea4854, ghrun-e3snp7fbzi.auto.internal:10351): creating call state
2025-12-04 14:19:09,770 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ExecuteSchemeQuery, 44b856af-0f29-4d3a-b754-aa3507ea4854, ghrun-e3snp7fbzi.auto.internal:10351): request = { session_id: "ydb://session/3?node_id=50004&id=ZWU4ZjlmMTMtNDYxM2M1N2QtMjcyNWE0YTItNGE2YWJjOTY=" yql_text: "\n                CREATE TABLE `table_4` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 14:19:09,787 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:09,787 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, a269dacc-2e40-44eb-b477-f255d93847a3, localhost:19973): creating call state
2025-12-04 14:19:09,793 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, a269dacc-2e40-44eb-b477-f255d93847a3, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:09,795 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, a269dacc-2e40-44eb-b477-f255d93847a3, localhost:19973): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-e3snp7fbzi.auto.internal" port: 10351 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-12-04 14:19:09,833 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Resolved endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-e3snp7fbzi.auto.internal:10351, location , ssl: False>]>
2025-12-04 14:19:09,833 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:09,856 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:09,887 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ExecuteSchemeQuery, 44b856af-0f29-4d3a-b754-aa3507ea4854, ghrun-e3snp7fbzi.auto.internal:10351): response = { operation { ready: true status: SUCCESS } }
2025-12-04 14:19:09,888 - DEBUG - ydb.table.SessionPool.2836152  - put: Put on session <ydb.table.Session object at 0x7fe705372db0>
2025-12-04 14:19:09,888 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-04 14:19:09,895 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:09,895 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-04 14:19:09,901 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_&cmstid=72057594037936131&txid=1764857949896744&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 14:19:09,933 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-04 14:19:09,999 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:15558
2025-12-04 14:19:10,469 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-04 14:19:10,483 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-04 14:19:10,492 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2836152  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-04 14:19:10,492 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-04 14:19:19,399 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:19,401 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:19,412 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:19,419 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, a9a77284-adef-4d91-ac6c-eb58c8349ffe, localhost:19973): creating call state
2025-12-04 14:19:19,435 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, a9a77284-adef-4d91-ac6c-eb58c8349ffe, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:19,438 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, a9a77284-adef-4d91-ac6c-eb58c8349ffe, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:19,441 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:19,441 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:20,968 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:20,999 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:21,008 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:21,008 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 280ce80c-0e07-47e5-a5e2-ee95312c8998, localhost:19973): creating call state
2025-12-04 14:19:21,009 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 280ce80c-0e07-47e5-a5e2-ee95312c8998, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:21,046 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 280ce80c-0e07-47e5-a5e2-ee95312c8998, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:21,050 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:21,050 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:22,404 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:22,405 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:22,429 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:22,443 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 6abd87b6-7993-4311-acdb-5f04ce6cc7ee, localhost:19973): creating call state
2025-12-04 14:19:22,481 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 6abd87b6-7993-4311-acdb-5f04ce6cc7ee, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:22,499 - INFO - ydb.tests.library.harness.kikimr_runner.2836152  - stop: Stopped node localhost:10351/5
2025-12-04 14:19:22,524 - DEBUG - ydb.tests.library.fixtures.2836152  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-04 14:19:22,523 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 6abd87b6-7993-4311-acdb-5f04ce6cc7ee, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:22,562 - INFO - ya.test.2836152  - pytest_runtest_teardown: Test teardown
2025-12-04 14:19:22,590 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:19:22,599 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:19:22,599 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:19:22,575 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:22,601 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe705372db0>, reason: session-pool-terminated
2025-12-04 14:19:22,608 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:22,599 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:19:22,644 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe705372db0> is no longer active. Current active count 0.
2025-12-04 14:19:22,685 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, d2f56fb9-d255-4c8b-96eb-5ec76bda175a, ghrun-e3snp7fbzi.auto.internal:10351): creating call state
2025-12-04 14:19:22,686 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, d2f56fb9-d255-4c8b-96eb-5ec76bda175a, ghrun-e3snp7fbzi.auto.internal:10351): request = { session_id: "ydb://session/3?node_id=50004&id=ZWU4ZjlmMTMtNDYxM2M1N2QtMjcyNWE0YTItNGE2YWJjOTY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:22,739 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, d2f56fb9-d255-4c8b-96eb-5ec76bda175a, ghrun-e3snp7fbzi.auto.internal:10351): on response callback started
2025-12-04 14:19:22,740 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, d2f56fb9-d255-4c8b-96eb-5ec76bda175a, ghrun-e3snp7fbzi.auto.internal:10351): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:10351: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:10351: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T14:19:22.732028336+00:00", grpc_status:14}"
>
2025-12-04 14:19:22,775 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, d2f56fb9-d255-4c8b-96eb-5ec76bda175a, ghrun-e3snp7fbzi.auto.internal:10351): unhandled rpc error, disconnecting channel
2025-12-04 14:19:22,776 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:10351
2025-12-04 14:19:22,795 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe705372db0>
2025-12-04 14:19:22,812 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:19:22,796 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:22,835 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:19:22,881 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:19:22,881 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:19:22,881 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe70538da50>, reason: session-pool-terminated
2025-12-04 14:19:22,882 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe70538da50> is no longer active. Current active count 0.
2025-12-04 14:19:22,882 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, 375bfe8e-431a-4394-8f09-8db073df106c, ghrun-e3snp7fbzi.auto.internal:62724): creating call state
2025-12-04 14:19:22,883 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, 375bfe8e-431a-4394-8f09-8db073df106c, ghrun-e3snp7fbzi.auto.internal:62724): request = { session_id: "ydb://session/3?node_id=50003&id=OWU1YTM1NGItMWMyMzlhNGQtNjgwMGQyNzMtOWVmMGI5ZA==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:22,884 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:19:22,880 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:22,888 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, 375bfe8e-431a-4394-8f09-8db073df106c, ghrun-e3snp7fbzi.auto.internal:62724): on response callback started
2025-12-04 14:19:22,889 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 375bfe8e-431a-4394-8f09-8db073df106c, ghrun-e3snp7fbzi.auto.internal:62724): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:62724: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:62724: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T14:19:22.883745428+00:00", grpc_status:14}"
>
2025-12-04 14:19:22,890 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 375bfe8e-431a-4394-8f09-8db073df106c, ghrun-e3snp7fbzi.auto.internal:62724): unhandled rpc error, disconnecting channel
2025-12-04 14:19:22,891 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:62724
2025-12-04 14:19:22,892 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe70538da50>
2025-12-04 14:19:22,892 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:22,908 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:22,893 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:22,912 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, c038e8cf-c6dd-4a7f-bfdc-c62a7abe7047, localhost:19973): creating call state
2025-12-04 14:19:22,908 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:19:22,915 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, c038e8cf-c6dd-4a7f-bfdc-c62a7abe7047, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:22,915 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:19:22,918 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, c038e8cf-c6dd-4a7f-bfdc-c62a7abe7047, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:22,927 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:22,938 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:19:22,962 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:19:22,963 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe704d985b0>, reason: session-pool-terminated
2025-12-04 14:19:22,940 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:19:22,939 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:22,961 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, e47d3b5a-da72-4cfe-95f7-117f74d14141, localhost:19973): creating call state
2025-12-04 14:19:22,965 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe704d985b0> is no longer active. Current active count 0.
2025-12-04 14:19:22,966 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:22,987 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, e47d3b5a-da72-4cfe-95f7-117f74d14141, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:22,988 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, 3033f61d-2b32-430f-b46f-07aa63fdaefd, ghrun-e3snp7fbzi.auto.internal:4093): creating call state
2025-12-04 14:19:22,999 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, 3033f61d-2b32-430f-b46f-07aa63fdaefd, ghrun-e3snp7fbzi.auto.internal:4093): request = { session_id: "ydb://session/3?node_id=50002&id=MzE0NjA1NjItNDE1NzIwNDktYjhmNDY4OTgtNDJmMTgzYjc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:23,000 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, e47d3b5a-da72-4cfe-95f7-117f74d14141, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:23,024 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:23,024 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe704d985b0>
2025-12-04 14:19:23,025 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,026 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, 3033f61d-2b32-430f-b46f-07aa63fdaefd, ghrun-e3snp7fbzi.auto.internal:4093): on response callback started
2025-12-04 14:19:23,028 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:19:23,037 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 3033f61d-2b32-430f-b46f-07aa63fdaefd, ghrun-e3snp7fbzi.auto.internal:4093): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:4093: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:4093: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T14:19:23.022445366+00:00", grpc_status:14}"
>
2025-12-04 14:19:23,038 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:19:23,039 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 3033f61d-2b32-430f-b46f-07aa63fdaefd, ghrun-e3snp7fbzi.auto.internal:4093): unhandled rpc error, disconnecting channel
2025-12-04 14:19:23,041 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:19:23,042 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:19:23,066 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:4093
2025-12-04 14:19:23,067 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:19:23,070 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:23,070 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe706b95460>, reason: session-pool-terminated
2025-12-04 14:19:23,088 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe706b95460> is no longer active. Current active count 0.
2025-12-04 14:19:23,101 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, 7ed0871e-9453-45d5-84f8-2ba192e591b8, ghrun-e3snp7fbzi.auto.internal:15900): creating call state
2025-12-04 14:19:23,102 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, 7ed0871e-9453-45d5-84f8-2ba192e591b8, ghrun-e3snp7fbzi.auto.internal:15900): request = { session_id: "ydb://session/3?node_id=50001&id=MzFkMTQwMzMtYWM0NjNhZWEtYjdiYWNlZWItM2I2MmI2MTc=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:23,071 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:23,107 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, 7ed0871e-9453-45d5-84f8-2ba192e591b8, ghrun-e3snp7fbzi.auto.internal:15900): on response callback started
2025-12-04 14:19:23,149 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:23,161 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 7ed0871e-9453-45d5-84f8-2ba192e591b8, ghrun-e3snp7fbzi.auto.internal:15900): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:15900: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:15900: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T14:19:23.104512912+00:00", grpc_status:14}"
>
2025-12-04 14:19:23,188 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 7ed0871e-9453-45d5-84f8-2ba192e591b8, ghrun-e3snp7fbzi.auto.internal:15900): unhandled rpc error, disconnecting channel
2025-12-04 14:19:23,188 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:15900
2025-12-04 14:19:23,189 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe706b95460>
2025-12-04 14:19:23,190 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:23,195 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:23,163 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 030ca610-a773-409c-aab0-1e9c8157ada0, localhost:19973): creating call state
2025-12-04 14:19:23,230 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 030ca610-a773-409c-aab0-1e9c8157ada0, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:23,195 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:19:23,234 - DEBUG - ydb.table.SessionPool.2836152  - stop: Requested session pool stop.
2025-12-04 14:19:23,210 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:23,233 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 030ca610-a773-409c-aab0-1e9c8157ada0, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:23,275 - DEBUG - ydb.table.SessionPool.2836152  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 14:19:23,277 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 0f15b3b5-383f-473f-bb14-e99ff6081a70, localhost:19973): creating call state
2025-12-04 14:19:23,278 - DEBUG - ydb.table.SessionPool.2836152  - _terminate_event: Terminated session pool.
2025-12-04 14:19:23,320 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 0f15b3b5-383f-473f-bb14-e99ff6081a70, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:23,311 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroying sessions in active queue
2025-12-04 14:19:23,291 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:23,329 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7fe704fcf300>, reason: session-pool-terminated
2025-12-04 14:19:23,325 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 0f15b3b5-383f-473f-bb14-e99ff6081a70, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:23,345 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Session <ydb.table.Session object at 0x7fe704fcf300> is no longer active. Current active count 0.
2025-12-04 14:19:23,341 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,348 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:23,356 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,351 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(DeleteSession, 9ea7fc7b-ad8f-44c4-bdae-0b595c140235, ghrun-e3snp7fbzi.auto.internal:10351): creating call state
2025-12-04 14:19:23,362 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(DeleteSession, 9ea7fc7b-ad8f-44c4-bdae-0b595c140235, ghrun-e3snp7fbzi.auto.internal:10351): request = { session_id: "ydb://session/3?node_id=50000&id=MzU1OTkzNWUtNGVjZjkzZGYtY2QwMTM2ZDYtYWRkYjRkMDQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 14:19:23,364 - DEBUG - ydb.connection.2836152  - _on_response_callback: RpcState(DeleteSession, 9ea7fc7b-ad8f-44c4-bdae-0b595c140235, ghrun-e3snp7fbzi.auto.internal:10351): on response callback started
2025-12-04 14:19:23,365 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 9ea7fc7b-ad8f-44c4-bdae-0b595c140235, ghrun-e3snp7fbzi.auto.internal:10351): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:10351: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:10351: Failed to connect to remote host: Connection refused {grpc_status:14, created_time:"2025-12-04T14:19:23.363716374+00:00", file_line:153, file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc"}"
>
2025-12-04 14:19:23,372 - DEBUG - ydb.connection.2836152  - _rpc_error_handler: RpcState(DeleteSession, 9ea7fc7b-ad8f-44c4-bdae-0b595c140235, ghrun-e3snp7fbzi.auto.internal:10351): unhandled rpc error, disconnecting channel
2025-12-04 14:19:23,377 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint ghrun-e3snp7fbzi.auto.internal:10351
2025-12-04 14:19:23,378 - DEBUG - ydb.table.SessionPool.2836152  - _destroy: Sent delete on session <ydb.table.Session object at 0x7fe704fcf300>
2025-12-04 14:19:23,379 - DEBUG - ydb.table.SessionPool.2836152  - stop: Destroyed active sessions
2025-12-04 14:19:23,383 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:19:23,379 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 14:19:23,412 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:19:23,431 - DEBUG - ydb.connection.2836152  - 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-12-04 14:19:23,433 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,434 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
2025-12-04 14:19:23,455 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:19:23,456 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:19:23,456 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,456 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
2025-12-04 14:19:23,477 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:19:23,502 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-04 14:19:23,535 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:19:23,536 - DEBUG - ydb.connection.2836152  - _prepare_call: RpcState(ListEndpoints, 15ed95ea-ea33-49df-96eb-f38a98642b27, localhost:19973): creating call state
2025-12-04 14:19:23,555 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,556 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
2025-12-04 14:19:23,602 - DEBUG - ydb.connection.2836152  - _log_request: RpcState(ListEndpoints, 15ed95ea-ea33-49df-96eb-f38a98642b27, localhost:19973): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-04 14:19:23,629 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:19:23,655 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:19:23,656 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,631 - DEBUG - ydb.connection.2836152  - _log_response: RpcState(ListEndpoints, 15ed95ea-ea33-49df-96eb-f38a98642b27, localhost:19973): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 14:19:23,658 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
2025-12-04 14:19:23,673 - DEBUG - ydb.topic.2836152  - close: Close topic client
2025-12-04 14:19:23,673 - DEBUG - ydb.topic.2836152  - close: Topic client was closed
2025-12-04 14:19:23,674 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,670 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2836152  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:19973". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 14:19:23,679 - DEBUG - ydb.connection.2836152  - close: Closing channel for endpoint localhost:19973
2025-12-04 14:19:23,707 - INFO - ydb.pool.Discovery.2836152  - run: Successfully terminated discovery process
