2025-12-12 16:06:34,372 - INFO - ya.test.1469336  - pytest_runtest_setup: ####################################################################################################
2025-12-12 16:06:34,372 - INFO - ya.test.1469336  - pytest_runtest_setup: test_stop_start[enable_alter_database_create_hive_first--true]
2025-12-12 16:06:34,372 - INFO - ya.test.1469336  - pytest_runtest_setup: ####################################################################################################
2025-12-12 16:06:34,372 - INFO - ya.test.1469336  - pytest_runtest_setup: Test setup
2025-12-12 16:06:34,373 - INFO - ydb.tests.library.fixtures.1469336  - ydb_cluster: destroy ydb_cluster for __tests__.test_tenants
2025-12-12 16:06:41,375 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - stop: Stopped node localhost:14279/1
2025-12-12 16:06:41,376 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12708
2025-12-12 16:06:41,376 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10871
2025-12-12 16:06:41,377 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2958
2025-12-12 16:06:41,377 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30378
2025-12-12 16:06:41,377 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11390
2025-12-12 16:06:41,377 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5400
2025-12-12 16:06:41,377 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6358
2025-12-12 16:06:41,378 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18105
2025-12-12 16:06:41,378 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63496
2025-12-12 16:06:41,378 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30706
2025-12-12 16:06:41,378 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30076
2025-12-12 16:06:41,378 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27370
2025-12-12 16:06:41,379 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1939
2025-12-12 16:06:41,379 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26627
2025-12-12 16:06:41,379 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7775
2025-12-12 16:06:41,379 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28135
2025-12-12 16:06:41,379 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9688
2025-12-12 16:06:41,380 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61677
2025-12-12 16:06:41,380 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23428
2025-12-12 16:06:41,382 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22434
2025-12-12 16:06:41,382 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15428
2025-12-12 16:06:41,382 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31633
2025-12-12 16:06:41,383 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4464
2025-12-12 16:06:41,383 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17893
2025-12-12 16:06:41,383 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7672
2025-12-12 16:06:41,383 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31068
2025-12-12 16:06:41,383 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23500
2025-12-12 16:06:41,384 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6818
2025-12-12 16:06:41,384 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14279
2025-12-12 16:06:41,384 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27654
2025-12-12 16:06:41,452 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:06:41,453 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12260
2025-12-12 16:06:41,455 - INFO - ydb.tests.library.fixtures.1469336  - ydb_cluster: setup ydb_cluster for __tests__.test_tenants
2025-12-12 16:06:41,456 - INFO - ydb.tests.library.fixtures.1469336  - ydb_cluster: setup ydb_cluster as local
2025-12-12 16:06:41,460 - DEBUG - ydb.tests.library.harness.kikimr_runner.1469336  - start: Working directory: /home/runner/.ya/build/build_root/dbm4/004c7c/r3tmp/kikimr_cluster__x2zxjpe
2025-12-12 16:06:41,500 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:06:41,501 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17745
2025-12-12 16:06:41,501 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3317
2025-12-12 16:06:41,502 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17028
2025-12-12 16:06:41,502 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27990
2025-12-12 16:06:41,503 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:06:41,503 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/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/dbm4/004c7c/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_9l3y1gh4.log --grpc-port=17745 --mon-port=3317 --ic-port=12260
2025-12-12 16:06:41,504 - DEBUG - ydb.tests.library.harness.kikimr_runner.1469336  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:17745/1, disk_size 68719476736
2025-12-12 16:06:41,504 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:06:41,504 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/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/dbm4/004c7c/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_9l3y1gh4.log --grpc-port=17745 --mon-port=3317 --ic-port=12260
2025-12-12 16:06:41,505 - DEBUG - ya.test.1469336  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c7c/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/dbm4/004c7c/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_9l3y1gh4.log', '--grpc-port=17745', '--mon-port=3317', '--ic-port=12260')' in '/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:06:41,507 - DEBUG - ya.test.1469336  - execute: Command pid: 1591986
2025-12-12 16:06:41,507 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Started node localhost:17745/1
2025-12-12 16:06:41,508 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.15
2025-12-12 16:06:41,509 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.15
2025-12-12 16:06:41,509 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:06:41,510 - DEBUG - ya.test.1469336  - execute: Command pid: 1591987
2025-12-12 16:06:41,977 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) rc: 0
2025-12-12 16:06:41,978 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) elapsed time (sec): 0.4489107131958008
2025-12-12 16:06:41,978 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) idrss: 0
2025-12-12 16:06:41,978 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) inblock: 0
2025-12-12 16:06:41,978 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) isrss: 0
2025-12-12 16:06:41,978 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) ixrss: 0
2025-12-12 16:06:41,979 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) majflt: 0
2025-12-12 16:06:41,979 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) maxrss: 547580
2025-12-12 16:06:41,989 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) minflt: 16266
2025-12-12 16:06:41,989 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) msgrcv: 0
2025-12-12 16:06:41,989 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) msgsnd: 0
2025-12-12 16:06:41,990 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) nivcsw: 55
2025-12-12 16:06:41,990 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) nsignals: 0
2025-12-12 16:06:41,990 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) nswap: 0
2025-12-12 16:06:41,990 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) nvcsw: 14
2025-12-12 16:06:41,990 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) oublock: 8
2025-12-12 16:06:41,991 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) stime: 0.243224
2025-12-12 16:06:41,991 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) utime: 0.12845399999999998
2025-12-12 16:06:41,991 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) wtime: 0.45
2025-12-12 16:06:41,991 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12 16:06:41,992 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1591987) errors:

2025-12-12 16:06:42,000 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Node 1 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:06:43,910 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:06:43,912 - DEBUG - ydb.tests.library.harness.kikimr_runner.1469336  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17745', '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: 12260\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-12 16:06:43,914 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.16
2025-12-12 16:06:43,915 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.16
2025-12-12 16:06:43,915 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17745', '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: 12260\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-12 16:06:43,917 - DEBUG - ya.test.1469336  - execute: Command pid: 1593482
2025-12-12 16:06:44,370 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) rc: 0
2025-12-12 16:06:44,373 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) elapsed time (sec): 0.45266199111938477
2025-12-12 16:06:44,373 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) idrss: 0
2025-12-12 16:06:44,373 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) inblock: 0
2025-12-12 16:06:44,373 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) isrss: 0
2025-12-12 16:06:44,373 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) ixrss: 0
2025-12-12 16:06:44,374 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) majflt: 0
2025-12-12 16:06:44,374 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) maxrss: 556044
2025-12-12 16:06:44,374 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) minflt: 18410
2025-12-12 16:06:44,374 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) msgrcv: 0
2025-12-12 16:06:44,374 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) msgsnd: 0
2025-12-12 16:06:44,374 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) nivcsw: 49
2025-12-12 16:06:44,375 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) nsignals: 0
2025-12-12 16:06:44,375 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) nswap: 0
2025-12-12 16:06:44,375 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) nvcsw: 131
2025-12-12 16:06:44,375 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) oublock: 8
2025-12-12 16:06:44,375 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) stime: 0.252476
2025-12-12 16:06:44,375 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) utime: 0.138072
2025-12-12 16:06:44,376 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) wtime: 0.455
2025-12-12 16:06:44,376 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-12 16:06:44,376 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593482) errors:

2025-12-12 16:06:44,378 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:06:44,378 - DEBUG - ydb.tests.library.harness.kikimr_runner.1469336  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17745', '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-12 16:06:44,379 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.17
2025-12-12 16:06:44,380 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.17
2025-12-12 16:06:44,380 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17745', '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-12 16:06:44,381 - DEBUG - ya.test.1469336  - execute: Command pid: 1593571
2025-12-12 16:06:44,797 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) rc: 0
2025-12-12 16:06:44,798 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) elapsed time (sec): 0.414700984954834
2025-12-12 16:06:44,798 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) idrss: 0
2025-12-12 16:06:44,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) inblock: 0
2025-12-12 16:06:44,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) isrss: 0
2025-12-12 16:06:44,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) ixrss: 0
2025-12-12 16:06:44,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) majflt: 0
2025-12-12 16:06:44,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) maxrss: 556064
2025-12-12 16:06:44,800 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) minflt: 18411
2025-12-12 16:06:44,800 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) msgrcv: 0
2025-12-12 16:06:44,800 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) msgsnd: 0
2025-12-12 16:06:44,800 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) nivcsw: 15
2025-12-12 16:06:44,800 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) nsignals: 0
2025-12-12 16:06:44,800 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) nswap: 0
2025-12-12 16:06:44,800 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) nvcsw: 117
2025-12-12 16:06:44,801 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) oublock: 8
2025-12-12 16:06:44,801 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) stime: 0.23815699999999998
2025-12-12 16:06:44,801 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) utime: 0.126935
2025-12-12 16:06:44,801 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) wtime: 0.416
2025-12-12 16:06:44,801 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-12 16:06:44,801 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1593571) errors:

2025-12-12 16:06:44,802 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-12 16:06:44,817 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __run: Cluster started and initialized
2025-12-12 16:06:44,837 - INFO - ya.test.1469336  - 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-12 16:06:44,838 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:06:44,838 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:06:44,842 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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-12 16:06:44,843 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:06:45,351 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:06:45,351 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:06:45,375 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:06:45,375 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-12 16:06:45,930 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-12 16:06:45,931 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:06:45,933 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3219
2025-12-12 16:06:45,934 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17006
2025-12-12 16:06:45,934 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7975
2025-12-12 16:06:45,935 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13603
2025-12-12 16:06:45,936 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63699
2025-12-12 16:06:45,937 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:06:45,939 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_412y7hsg.log --grpc-port=3219 --mon-port=17006 --ic-port=7975
2025-12-12 16:06:45,941 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:06:45,945 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_412y7hsg.log --grpc-port=3219 --mon-port=17006 --ic-port=7975
2025-12-12 16:06:45,948 - DEBUG - ya.test.1469336  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c7c/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:17745', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_412y7hsg.log', '--grpc-port=3219', '--mon-port=17006', '--ic-port=7975')' in '/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:06:45,961 - DEBUG - ya.test.1469336  - execute: Command pid: 1595364
2025-12-12 16:06:45,961 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Started node localhost:3219/1
2025-12-12 16:06:45,964 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.18
2025-12-12 16:06:45,974 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.18
2025-12-12 16:06:45,983 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:06:45,990 - DEBUG - ya.test.1469336  - execute: Command pid: 1595368
2025-12-12 16:06:46,434 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) rc: 0
2025-12-12 16:06:46,434 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) elapsed time (sec): 0.4426116943359375
2025-12-12 16:06:46,434 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) idrss: 0
2025-12-12 16:06:46,435 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) inblock: 0
2025-12-12 16:06:46,435 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) isrss: 0
2025-12-12 16:06:46,435 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) ixrss: 0
2025-12-12 16:06:46,435 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) majflt: 0
2025-12-12 16:06:46,435 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) maxrss: 547580
2025-12-12 16:06:46,435 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) minflt: 16268
2025-12-12 16:06:46,436 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) msgrcv: 0
2025-12-12 16:06:46,436 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) msgsnd: 0
2025-12-12 16:06:46,436 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) nivcsw: 72
2025-12-12 16:06:46,436 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) nsignals: 0
2025-12-12 16:06:46,436 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) nswap: 0
2025-12-12 16:06:46,436 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) nvcsw: 15
2025-12-12 16:06:46,437 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) oublock: 8
2025-12-12 16:06:46,437 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) stime: 0.269344
2025-12-12 16:06:46,437 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) utime: 0.09832099999999999
2025-12-12 16:06:46,437 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) wtime: 0.45
2025-12-12 16:06:46,437 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12 16:06:46,438 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1595368) errors:

2025-12-12 16:06:46,438 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Node 1 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:06:46,438 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-12 16:06:48,448 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-12 16:06:48,448 - DEBUG - ydb.connection.1469336  - 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-12 16:06:48,451 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:06:48,452 - DEBUG - ydb.connection.1469336  - 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-12 16:06:48,456 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:06:48,456 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Subscribe waiter <Future at 0x7f4c76344740 state=pending>
2025-12-12 16:06:48,463 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-12 16:06:48,463 - DEBUG - ydb.table.SessionPool.1469336  - _create: Created session <ydb.table.Session object at 0x7f4c76345b40>
2025-12-12 16:06:48,464 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c76345b40>
2025-12-12 16:06:48,464 - ERROR - ydb.table.SessionPool.1469336  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:06:48,459 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:06:48,466 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 077af7c9-16c7-4c39-a103-6a31e7cb8b6e, localhost:17745): creating call state
2025-12-12 16:06:48,466 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 077af7c9-16c7-4c39-a103-6a31e7cb8b6e, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:06:48,469 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 077af7c9-16c7-4c39-a103-6a31e7cb8b6e, localhost:17745): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 3219 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-12 16:06:48,469 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _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-jumawwbfgm.auto.internal:3219, location , ssl: False>]>
2025-12-12 16:06:48,470 - DEBUG - ydb.connection.1469336  - 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-12 16:06:48,477 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:06:48,478 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c76345b40>
2025-12-12 16:06:48,479 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(CreateSession, 6feaa752-9614-4ecb-be1a-fbcddf2eca2f, ghrun-jumawwbfgm.auto.internal:3219): creating call state
2025-12-12 16:06:48,480 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(CreateSession, 6feaa752-9614-4ecb-be1a-fbcddf2eca2f, ghrun-jumawwbfgm.auto.internal:3219): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:06:48,483 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 6feaa752-9614-4ecb-be1a-fbcddf2eca2f, ghrun-jumawwbfgm.auto.internal:3219): on response callback started
2025-12-12 16:06:48,484 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(CreateSession, 6feaa752-9614-4ecb-be1a-fbcddf2eca2f, ghrun-jumawwbfgm.auto.internal:3219): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=OWNiMDEzMWMtNjRjZDE0MmMtZDIxYzZkNmUtY2U0MDFjYzA=" } } } }
2025-12-12 16:06:48,484 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c76345b40>
2025-12-12 16:06:48,484 - DEBUG - ydb.table.SessionPool.1469336  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4c76345b40>
2025-12-12 16:06:48,484 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 6feaa752-9614-4ecb-be1a-fbcddf2eca2f, ghrun-jumawwbfgm.auto.internal:3219): on response callback success
2025-12-12 16:06:48,485 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ExecuteSchemeQuery, 4053fa70-3b14-43e1-8c8d-eb6f38865213, ghrun-jumawwbfgm.auto.internal:3219): creating call state
2025-12-12 16:06:48,486 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ExecuteSchemeQuery, 4053fa70-3b14-43e1-8c8d-eb6f38865213, ghrun-jumawwbfgm.auto.internal:3219): request = { session_id: "ydb://session/3?node_id=50000&id=OWNiMDEzMWMtNjRjZDE0MmMtZDIxYzZkNmUtY2U0MDFjYzA=" 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-12 16:06:48,554 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ExecuteSchemeQuery, 4053fa70-3b14-43e1-8c8d-eb6f38865213, ghrun-jumawwbfgm.auto.internal:3219): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:06:48,555 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c76345b40>
2025-12-12 16:06:48,556 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-12 16:06:48,556 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:06:48,556 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:06:48,566 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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=1765555608558317&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:06:48,566 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:06:49,075 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:06:49,076 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:06:49,080 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:06:49,080 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-12 16:07:01,083 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - stop: Stopped node localhost:3219/1
2025-12-12 16:07:01,083 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-12 16:07:01,083 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:07:01,084 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:07:01,087 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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-12 16:07:01,088 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:07:01,592 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:07:01,593 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:07:01,597 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:07:01,598 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-12 16:07:02,113 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-12 16:07:02,113 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:07:02,114 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25021
2025-12-12 16:07:02,114 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8358
2025-12-12 16:07:02,121 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13620
2025-12-12 16:07:02,121 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1688
2025-12-12 16:07:02,122 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20084
2025-12-12 16:07:02,123 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:02,123 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_q74p40zv.log --grpc-port=25021 --mon-port=8358 --ic-port=13620
2025-12-12 16:07:02,123 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:02,123 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_q74p40zv.log --grpc-port=25021 --mon-port=8358 --ic-port=13620
2025-12-12 16:07:02,124 - DEBUG - ya.test.1469336  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c7c/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:17745', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_q74p40zv.log', '--grpc-port=25021', '--mon-port=8358', '--ic-port=13620')' in '/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:02,129 - DEBUG - ya.test.1469336  - execute: Command pid: 1606440
2025-12-12 16:07:02,130 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Started node localhost:25021/2
2025-12-12 16:07:02,139 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.19
2025-12-12 16:07:02,141 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.19
2025-12-12 16:07:02,142 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:07:02,143 - DEBUG - ya.test.1469336  - execute: Command pid: 1606443
2025-12-12 16:07:02,651 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) rc: 0
2025-12-12 16:07:02,651 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) elapsed time (sec): 0.5064396858215332
2025-12-12 16:07:02,652 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) idrss: 0
2025-12-12 16:07:02,653 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) inblock: 0
2025-12-12 16:07:02,653 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) isrss: 0
2025-12-12 16:07:02,653 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) ixrss: 0
2025-12-12 16:07:02,653 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) majflt: 0
2025-12-12 16:07:02,653 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) maxrss: 547580
2025-12-12 16:07:02,655 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) minflt: 16270
2025-12-12 16:07:02,655 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) msgrcv: 0
2025-12-12 16:07:02,655 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) msgsnd: 0
2025-12-12 16:07:02,655 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) nivcsw: 103
2025-12-12 16:07:02,656 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) nsignals: 0
2025-12-12 16:07:02,656 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) nswap: 0
2025-12-12 16:07:02,656 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) nvcsw: 21
2025-12-12 16:07:02,656 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) oublock: 8
2025-12-12 16:07:02,656 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) stime: 0.276513
2025-12-12 16:07:02,656 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) utime: 0.118505
2025-12-12 16:07:02,657 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) wtime: 0.508
2025-12-12 16:07:02,657 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12 16:07:02,657 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1606443) errors:

2025-12-12 16:07:02,657 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Node 2 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:07:02,657 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-12 16:07:04,666 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-12 16:07:04,667 - DEBUG - ydb.connection.1469336  - 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-12 16:07:04,671 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:04,672 - DEBUG - ydb.connection.1469336  - 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-12 16:07:04,672 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:07:04,678 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:07:04,679 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Subscribe waiter <Future at 0x7f4c755648d0 state=pending>
2025-12-12 16:07:04,684 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 731c450a-eaae-4d58-9922-629aefd0cd13, localhost:17745): creating call state
2025-12-12 16:07:04,684 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-12 16:07:04,686 - DEBUG - ydb.table.SessionPool.1469336  - _create: Created session <ydb.table.Session object at 0x7f4c72ddb0d0>
2025-12-12 16:07:04,686 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c72ddb0d0>
2025-12-12 16:07:04,687 - ERROR - ydb.table.SessionPool.1469336  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:07:04,686 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 731c450a-eaae-4d58-9922-629aefd0cd13, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:07:04,692 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 731c450a-eaae-4d58-9922-629aefd0cd13, localhost:17745): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 25021 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-12 16:07:04,692 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _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-jumawwbfgm.auto.internal:25021, location , ssl: False>]>
2025-12-12 16:07:04,693 - DEBUG - ydb.connection.1469336  - 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-12 16:07:04,703 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:07:04,703 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c72ddb0d0>
2025-12-12 16:07:04,704 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(CreateSession, 83bd67d5-9031-491c-b5fd-bf15f0560260, ghrun-jumawwbfgm.auto.internal:25021): creating call state
2025-12-12 16:07:04,705 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(CreateSession, 83bd67d5-9031-491c-b5fd-bf15f0560260, ghrun-jumawwbfgm.auto.internal:25021): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:04,707 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 83bd67d5-9031-491c-b5fd-bf15f0560260, ghrun-jumawwbfgm.auto.internal:25021): on response callback started
2025-12-12 16:07:04,708 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(CreateSession, 83bd67d5-9031-491c-b5fd-bf15f0560260, ghrun-jumawwbfgm.auto.internal:25021): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=NTE2NjI0MWItN2VhZGZkNTctZTRlMGNiZjQtMTdkNjllOWY=" } } } }
2025-12-12 16:07:04,708 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c72ddb0d0>
2025-12-12 16:07:04,708 - DEBUG - ydb.table.SessionPool.1469336  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4c72ddb0d0>
2025-12-12 16:07:04,709 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 83bd67d5-9031-491c-b5fd-bf15f0560260, ghrun-jumawwbfgm.auto.internal:25021): on response callback success
2025-12-12 16:07:04,709 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ExecuteSchemeQuery, a410c755-9cd9-4325-8f49-0770012f986c, ghrun-jumawwbfgm.auto.internal:25021): creating call state
2025-12-12 16:07:04,711 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ExecuteSchemeQuery, a410c755-9cd9-4325-8f49-0770012f986c, ghrun-jumawwbfgm.auto.internal:25021): request = { session_id: "ydb://session/3?node_id=50001&id=NTE2NjI0MWItN2VhZGZkNTctZTRlMGNiZjQtMTdkNjllOWY=" 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-12 16:07:04,786 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ExecuteSchemeQuery, a410c755-9cd9-4325-8f49-0770012f986c, ghrun-jumawwbfgm.auto.internal:25021): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:04,787 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c72ddb0d0>
2025-12-12 16:07:04,787 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-12 16:07:04,788 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:07:04,788 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:07:04,793 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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=1765555624790793&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:07:04,794 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:07:05,297 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:07:05,298 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:07:05,301 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:07:05,301 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-12 16:07:27,303 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - stop: Stopped node localhost:25021/2
2025-12-12 16:07:27,303 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-12 16:07:27,304 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:07:27,304 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:07:27,307 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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-12 16:07:27,307 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:07:27,812 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:07:27,812 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:07:27,816 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:07:27,816 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-12 16:07:28,328 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-12 16:07:28,328 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:07:28,329 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10743
2025-12-12 16:07:28,329 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20574
2025-12-12 16:07:28,330 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18742
2025-12-12 16:07:28,330 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22448
2025-12-12 16:07:28,330 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22449
2025-12-12 16:07:28,330 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30449
2025-12-12 16:07:28,331 - DEBUG - library.python.filelock.1469336  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30449
2025-12-12 16:07:28,331 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30450
2025-12-12 16:07:28,331 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:28,332 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_ju_i8nmg.log --grpc-port=10743 --mon-port=20574 --ic-port=18742
2025-12-12 16:07:28,332 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:28,332 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_ju_i8nmg.log --grpc-port=10743 --mon-port=20574 --ic-port=18742
2025-12-12 16:07:28,333 - DEBUG - ya.test.1469336  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c7c/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:17745', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_ju_i8nmg.log', '--grpc-port=10743', '--mon-port=20574', '--ic-port=18742')' in '/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:28,334 - DEBUG - ya.test.1469336  - execute: Command pid: 1621283
2025-12-12 16:07:28,335 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Started node localhost:10743/3
2025-12-12 16:07:28,336 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.20
2025-12-12 16:07:28,337 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.20
2025-12-12 16:07:28,337 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:07:28,338 - DEBUG - ya.test.1469336  - execute: Command pid: 1621287
2025-12-12 16:07:28,794 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) rc: 0
2025-12-12 16:07:28,795 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) elapsed time (sec): 0.45598316192626953
2025-12-12 16:07:28,795 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) idrss: 0
2025-12-12 16:07:28,795 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) inblock: 0
2025-12-12 16:07:28,795 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) isrss: 0
2025-12-12 16:07:28,796 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) ixrss: 0
2025-12-12 16:07:28,796 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) majflt: 3
2025-12-12 16:07:28,796 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) maxrss: 547580
2025-12-12 16:07:28,796 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) minflt: 20207
2025-12-12 16:07:28,796 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) msgrcv: 0
2025-12-12 16:07:28,797 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) msgsnd: 0
2025-12-12 16:07:28,797 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) nivcsw: 24
2025-12-12 16:07:28,797 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) nsignals: 0
2025-12-12 16:07:28,798 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) nswap: 0
2025-12-12 16:07:28,798 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) nvcsw: 19
2025-12-12 16:07:28,798 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) oublock: 8
2025-12-12 16:07:28,798 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) stime: 0.27766599999999997
2025-12-12 16:07:28,798 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) utime: 0.126933
2025-12-12 16:07:28,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) wtime: 0.457
2025-12-12 16:07:28,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12 16:07:28,799 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1621287) errors:

2025-12-12 16:07:28,799 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Node 3 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:07:28,800 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-12 16:07:30,808 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-12 16:07:30,810 - DEBUG - ydb.connection.1469336  - 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-12 16:07:30,812 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:07:30,813 - DEBUG - ydb.connection.1469336  - 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-12 16:07:30,814 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:07:30,818 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Subscribe waiter <Future at 0x7f4c763626d0 state=pending>
2025-12-12 16:07:30,818 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-12 16:07:30,818 - DEBUG - ydb.table.SessionPool.1469336  - _create: Created session <ydb.table.Session object at 0x7f4c7285a5e0>
2025-12-12 16:07:30,819 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c7285a5e0>
2025-12-12 16:07:30,819 - ERROR - ydb.table.SessionPool.1469336  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:07:30,822 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:07:30,822 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 223758b8-07c5-45de-81ca-60e5a6626b7c, localhost:17745): creating call state
2025-12-12 16:07:30,823 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 223758b8-07c5-45de-81ca-60e5a6626b7c, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:07:30,825 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 223758b8-07c5-45de-81ca-60e5a6626b7c, localhost:17745): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 10743 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-12 16:07:30,826 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _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-jumawwbfgm.auto.internal:10743, location , ssl: False>]>
2025-12-12 16:07:30,826 - DEBUG - ydb.connection.1469336  - 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-12 16:07:30,834 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:07:30,834 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c7285a5e0>
2025-12-12 16:07:30,835 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(CreateSession, fd1c18b1-9cdb-4ed0-9f94-b8638a4b2142, ghrun-jumawwbfgm.auto.internal:10743): creating call state
2025-12-12 16:07:30,835 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(CreateSession, fd1c18b1-9cdb-4ed0-9f94-b8638a4b2142, ghrun-jumawwbfgm.auto.internal:10743): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:07:30,838 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, fd1c18b1-9cdb-4ed0-9f94-b8638a4b2142, ghrun-jumawwbfgm.auto.internal:10743): on response callback started
2025-12-12 16:07:30,838 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(CreateSession, fd1c18b1-9cdb-4ed0-9f94-b8638a4b2142, ghrun-jumawwbfgm.auto.internal:10743): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=MzM0NmY5M2ItOWIyZTE0ZmEtNzFlODQzYjctMWRmZGJiN2E=" } } } }
2025-12-12 16:07:30,839 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c7285a5e0>
2025-12-12 16:07:30,839 - DEBUG - ydb.table.SessionPool.1469336  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4c7285a5e0>
2025-12-12 16:07:30,839 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ExecuteSchemeQuery, c1359ca8-7077-4009-b04e-fc8a2fb2c66e, ghrun-jumawwbfgm.auto.internal:10743): creating call state
2025-12-12 16:07:30,840 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, fd1c18b1-9cdb-4ed0-9f94-b8638a4b2142, ghrun-jumawwbfgm.auto.internal:10743): on response callback success
2025-12-12 16:07:30,840 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ExecuteSchemeQuery, c1359ca8-7077-4009-b04e-fc8a2fb2c66e, ghrun-jumawwbfgm.auto.internal:10743): request = { session_id: "ydb://session/3?node_id=50002&id=MzM0NmY5M2ItOWIyZTE0ZmEtNzFlODQzYjctMWRmZGJiN2E=" 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-12 16:07:30,912 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ExecuteSchemeQuery, c1359ca8-7077-4009-b04e-fc8a2fb2c66e, ghrun-jumawwbfgm.auto.internal:10743): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:07:30,913 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c7285a5e0>
2025-12-12 16:07:30,913 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-12 16:07:30,914 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:07:30,914 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:07:30,918 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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=1765555650916085&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:07:30,918 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:07:31,424 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:07:31,424 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:07:31,427 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:07:31,428 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-12 16:07:58,434 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - stop: Stopped node localhost:10743/3
2025-12-12 16:07:58,434 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-12 16:07:58,434 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:07:58,434 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:07:58,442 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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-12 16:07:58,442 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:07:58,946 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:07:58,947 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:07:58,949 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:07:58,950 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-12 16:07:59,472 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-12 16:07:59,472 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:07:59,473 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63259
2025-12-12 16:07:59,473 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27900
2025-12-12 16:07:59,473 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10351
2025-12-12 16:07:59,474 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64748
2025-12-12 16:07:59,474 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26209
2025-12-12 16:07:59,475 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:59,475 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_vrs73jpd.log --grpc-port=63259 --mon-port=27900 --ic-port=10351
2025-12-12 16:07:59,476 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:59,476 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_vrs73jpd.log --grpc-port=63259 --mon-port=27900 --ic-port=10351
2025-12-12 16:07:59,477 - DEBUG - ya.test.1469336  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c7c/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:17745', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_vrs73jpd.log', '--grpc-port=63259', '--mon-port=27900', '--ic-port=10351')' in '/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:07:59,478 - DEBUG - ya.test.1469336  - execute: Command pid: 1636858
2025-12-12 16:07:59,479 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Started node localhost:63259/4
2025-12-12 16:07:59,480 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.21
2025-12-12 16:07:59,481 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.21
2025-12-12 16:07:59,481 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:07:59,487 - DEBUG - ya.test.1469336  - execute: Command pid: 1636860
2025-12-12 16:07:59,909 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) rc: 0
2025-12-12 16:07:59,909 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) elapsed time (sec): 0.42131757736206055
2025-12-12 16:07:59,909 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) idrss: 0
2025-12-12 16:07:59,910 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) inblock: 0
2025-12-12 16:07:59,910 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) isrss: 0
2025-12-12 16:07:59,910 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) ixrss: 0
2025-12-12 16:07:59,910 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) majflt: 0
2025-12-12 16:07:59,910 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) maxrss: 547656
2025-12-12 16:07:59,910 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) minflt: 17734
2025-12-12 16:07:59,911 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) msgrcv: 0
2025-12-12 16:07:59,911 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) msgsnd: 0
2025-12-12 16:07:59,911 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) nivcsw: 53
2025-12-12 16:07:59,911 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) nsignals: 0
2025-12-12 16:07:59,911 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) nswap: 0
2025-12-12 16:07:59,912 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) nvcsw: 22
2025-12-12 16:07:59,912 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) oublock: 8
2025-12-12 16:07:59,912 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) stime: 0.23221599999999998
2025-12-12 16:07:59,912 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) utime: 0.11225399999999999
2025-12-12 16:07:59,912 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) wtime: 0.427
2025-12-12 16:07:59,912 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12 16:07:59,913 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1636860) errors:

2025-12-12 16:07:59,913 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Node 4 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:07:59,913 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-12 16:08:05,925 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-12 16:08:05,926 - DEBUG - ydb.connection.1469336  - 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-12 16:08:05,929 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:05,930 - DEBUG - ydb.connection.1469336  - 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-12 16:08:05,930 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:08:05,934 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Subscribe waiter <Future at 0x7f4c763ad5a0 state=pending>
2025-12-12 16:08:05,934 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-12 16:08:05,935 - DEBUG - ydb.table.SessionPool.1469336  - _create: Created session <ydb.table.Session object at 0x7f4c763a9460>
2025-12-12 16:08:05,935 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c763a9460>
2025-12-12 16:08:05,936 - ERROR - ydb.table.SessionPool.1469336  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:08:05,938 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:05,938 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 832d9c3c-806b-4da3-a4db-4ff1f297da93, localhost:17745): creating call state
2025-12-12 16:08:05,939 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 832d9c3c-806b-4da3-a4db-4ff1f297da93, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:05,941 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 832d9c3c-806b-4da3-a4db-4ff1f297da93, localhost:17745): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 63259 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-12 16:08:05,942 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _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-jumawwbfgm.auto.internal:63259, location , ssl: False>]>
2025-12-12 16:08:05,942 - DEBUG - ydb.connection.1469336  - 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-12 16:08:05,949 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:05,950 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c763a9460>
2025-12-12 16:08:05,950 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(CreateSession, 69f50461-10df-467b-8e82-51a8ac8f3766, ghrun-jumawwbfgm.auto.internal:63259): creating call state
2025-12-12 16:08:05,951 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(CreateSession, 69f50461-10df-467b-8e82-51a8ac8f3766, ghrun-jumawwbfgm.auto.internal:63259): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:08:05,963 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 69f50461-10df-467b-8e82-51a8ac8f3766, ghrun-jumawwbfgm.auto.internal:63259): on response callback started
2025-12-12 16:08:05,963 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(CreateSession, 69f50461-10df-467b-8e82-51a8ac8f3766, ghrun-jumawwbfgm.auto.internal:63259): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=MmFmYzU3NjItODBlNTc4Mi1jMzcxZmYyLWUwNDZlZDNl" } } } }
2025-12-12 16:08:05,965 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 69f50461-10df-467b-8e82-51a8ac8f3766, ghrun-jumawwbfgm.auto.internal:63259): on response callback success
2025-12-12 16:08:05,965 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c763a9460>
2025-12-12 16:08:05,965 - DEBUG - ydb.table.SessionPool.1469336  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4c763a9460>
2025-12-12 16:08:05,966 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ExecuteSchemeQuery, 623ed34e-2b02-4710-b9ac-a9658184e02b, ghrun-jumawwbfgm.auto.internal:63259): creating call state
2025-12-12 16:08:05,966 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ExecuteSchemeQuery, 623ed34e-2b02-4710-b9ac-a9658184e02b, ghrun-jumawwbfgm.auto.internal:63259): request = { session_id: "ydb://session/3?node_id=50003&id=MmFmYzU3NjItODBlNTc4Mi1jMzcxZmYyLWUwNDZlZDNl" 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-12 16:08:06,047 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ExecuteSchemeQuery, 623ed34e-2b02-4710-b9ac-a9658184e02b, ghrun-jumawwbfgm.auto.internal:63259): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:08:06,048 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c763a9460>
2025-12-12 16:08:06,048 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-12 16:08:06,048 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:06,048 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:08:06,056 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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=1765555686053607&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:08:06,056 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:08:06,561 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:08:06,562 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:08:06,565 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:08:06,566 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-12 16:08:28,569 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - stop: Stopped node localhost:63259/4
2025-12-12 16:08:28,570 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-12 16:08:28,570 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:28,570 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:08:28,576 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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-12 16:08:28,576 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:08:29,082 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:08:29,082 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:08:29,085 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:08:29,086 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-12-12 16:08:29,602 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-12-12 16:08:29,603 - DEBUG - ya.test.1469336  - get_binary: Binary was found by /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd
2025-12-12 16:08:29,604 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17934
2025-12-12 16:08:29,604 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25850
2025-12-12 16:08:29,605 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11741
2025-12-12 16:08:29,607 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26888
2025-12-12 16:08:29,608 - DEBUG - library.python.filelock.1469336  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10816
2025-12-12 16:08:29,610 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:08:29,610 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_8ko1ge8m.log --grpc-port=17934 --mon-port=25850 --ic-port=11741
2025-12-12 16:08:29,611 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:08:29,611 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - __make_run_command: Final command: /home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:17745 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_8ko1ge8m.log --grpc-port=17934 --mon-port=25850 --ic-port=11741
2025-12-12 16:08:29,612 - DEBUG - ya.test.1469336  - execute: Executing '('/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/dbm4/004c7c/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:17745', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/dbm4/004c7c/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_8ko1ge8m.log', '--grpc-port=17934', '--mon-port=25850', '--ic-port=11741')' in '/home/runner/.ya/build/build_root/dbm4/004c7c/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-12 16:08:29,615 - DEBUG - ya.test.1469336  - execute: Command pid: 1646436
2025-12-12 16:08:29,616 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Started node localhost:17934/5
2025-12-12 16:08:29,617 - DEBUG - ya.test.1469336  - _get_command_output_file: Command out will be placed to ydbd.out.22
2025-12-12 16:08:29,617 - DEBUG - ya.test.1469336  - _get_command_output_file: Command err will be placed to ydbd.err.22
2025-12-12 16:08:29,618 - DEBUG - ya.test.1469336  - execute: Executing '['/home/runner/.ya/build/build_root/dbm4/004c7c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 16:08:29,618 - DEBUG - ya.test.1469336  - execute: Command pid: 1646438
2025-12-12 16:08:30,165 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) rc: 0
2025-12-12 16:08:30,166 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) elapsed time (sec): 0.5463497638702393
2025-12-12 16:08:30,166 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) idrss: 0
2025-12-12 16:08:30,166 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) inblock: 0
2025-12-12 16:08:30,166 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) isrss: 0
2025-12-12 16:08:30,166 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) ixrss: 0
2025-12-12 16:08:30,166 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) majflt: 0
2025-12-12 16:08:30,167 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) maxrss: 547592
2025-12-12 16:08:30,167 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) minflt: 17430
2025-12-12 16:08:30,167 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) msgrcv: 0
2025-12-12 16:08:30,167 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) msgsnd: 0
2025-12-12 16:08:30,167 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) nivcsw: 74
2025-12-12 16:08:30,167 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) nsignals: 0
2025-12-12 16:08:30,167 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) nswap: 0
2025-12-12 16:08:30,168 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) nvcsw: 15
2025-12-12 16:08:30,168 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) oublock: 8
2025-12-12 16:08:30,168 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) stime: 0.249412
2025-12-12 16:08:30,168 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) utime: 0.111578
2025-12-12 16:08:30,168 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) wtime: 0.547
2025-12-12 16:08:30,168 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) output:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12 16:08:30,169 - DEBUG - ya.test.1469336  - _save_outputs: Command (pid 1646438) errors:

2025-12-12 16:08:30,169 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - start: Node 5 version:
Git info:
    Commit: c2f1109ed7ce5ea304d2989e1e6e1d0c68e96358
    Branch: 
    Author: Aleksei Borzenkov <snaury@gmail.com>
    Summary: Merge 7c762dd6784fb9aa159b9be6517677a73afa7e00 into de6c05340f663d6c5b7e77f457f2e77c2b9d8b8f
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jumawwbfgm    Host information: 
        Linux ghrun-jumawwbfgm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-12 16:08:30,169 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-12-12 16:08:32,177 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-12-12 16:08:32,178 - DEBUG - ydb.connection.1469336  - 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-12 16:08:32,180 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:32,181 - DEBUG - ydb.connection.1469336  - 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-12 16:08:32,181 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-12 16:08:32,184 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Subscribe waiter <Future at 0x7f4c762efcb0 state=pending>
2025-12-12 16:08:32,185 - DEBUG - ydb.table.SessionPool.1469336  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-12 16:08:32,185 - DEBUG - ydb.table.SessionPool.1469336  - _create: Created session <ydb.table.Session object at 0x7f4c770c33f0>
2025-12-12 16:08:32,185 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c770c33f0>
2025-12-12 16:08:32,185 - ERROR - ydb.table.SessionPool.1469336  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-12 16:08:32,188 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:32,188 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, b988663a-f6b9-4272-b101-5d69152908c5, localhost:17745): creating call state
2025-12-12 16:08:32,189 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, b988663a-f6b9-4272-b101-5d69152908c5, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:32,196 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, b988663a-f6b9-4272-b101-5d69152908c5, localhost:17745): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jumawwbfgm.auto.internal" port: 17934 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-12 16:08:32,197 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _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-jumawwbfgm.auto.internal:17934, location , ssl: False>]>
2025-12-12 16:08:32,197 - DEBUG - ydb.connection.1469336  - 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-12 16:08:32,205 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:32,205 - DEBUG - ydb.table.SessionPool.1469336  - _prepare: Preparing session <ydb.table.Session object at 0x7f4c770c33f0>
2025-12-12 16:08:32,205 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(CreateSession, 041040c7-aaf5-4c75-aae1-9aefa8d80d90, ghrun-jumawwbfgm.auto.internal:17934): creating call state
2025-12-12 16:08:32,206 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(CreateSession, 041040c7-aaf5-4c75-aae1-9aefa8d80d90, ghrun-jumawwbfgm.auto.internal:17934): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:08:32,208 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 041040c7-aaf5-4c75-aae1-9aefa8d80d90, ghrun-jumawwbfgm.auto.internal:17934): on response callback started
2025-12-12 16:08:32,209 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(CreateSession, 041040c7-aaf5-4c75-aae1-9aefa8d80d90, ghrun-jumawwbfgm.auto.internal:17934): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50004&id=ZGZhODY4NDYtZTg4YzU2MDgtODg5MGE4ZTItMzlhYzAyNzQ=" } } } }
2025-12-12 16:08:32,209 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c770c33f0>
2025-12-12 16:08:32,209 - DEBUG - ydb.table.SessionPool.1469336  - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4c770c33f0>
2025-12-12 16:08:32,209 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(CreateSession, 041040c7-aaf5-4c75-aae1-9aefa8d80d90, ghrun-jumawwbfgm.auto.internal:17934): on response callback success
2025-12-12 16:08:32,210 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ExecuteSchemeQuery, 0ac0a407-daca-4d83-b85b-457ba525e71b, ghrun-jumawwbfgm.auto.internal:17934): creating call state
2025-12-12 16:08:32,211 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ExecuteSchemeQuery, 0ac0a407-daca-4d83-b85b-457ba525e71b, ghrun-jumawwbfgm.auto.internal:17934): request = { session_id: "ydb://session/3?node_id=50004&id=ZGZhODY4NDYtZTg4YzU2MDgtODg5MGE4ZTItMzlhYzAyNzQ=" 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-12 16:08:32,292 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ExecuteSchemeQuery, 0ac0a407-daca-4d83-b85b-457ba525e71b, ghrun-jumawwbfgm.auto.internal:17934): response = { operation { ready: true status: SUCCESS } }
2025-12-12 16:08:32,293 - DEBUG - ydb.table.SessionPool.1469336  - put: Put on session <ydb.table.Session object at 0x7f4c770c33f0>
2025-12-12 16:08:32,293 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-12-12 16:08:32,293 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:32,294 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-12-12 16:08:32,302 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _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=1765555712295437&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-12 16:08:32,303 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-12-12 16:08:32,806 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-12-12 16:08:32,807 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-12-12 16:08:32,810 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.1469336  - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-12-12 16:08:32,810 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-12-12 16:08:39,162 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:39,163 - DEBUG - ydb.connection.1469336  - 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-12 16:08:39,168 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:39,168 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, e025b137-a725-4d39-86dd-96df10b6cb85, localhost:17745): creating call state
2025-12-12 16:08:39,169 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, e025b137-a725-4d39-86dd-96df10b6cb85, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:39,172 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, e025b137-a725-4d39-86dd-96df10b6cb85, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:39,172 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:39,172 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:41,274 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:41,275 - DEBUG - ydb.connection.1469336  - 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-12 16:08:41,281 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:41,281 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 2330d8ef-ad7c-49d2-9f66-0fb87dc4e077, localhost:17745): creating call state
2025-12-12 16:08:41,281 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 2330d8ef-ad7c-49d2-9f66-0fb87dc4e077, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:41,284 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 2330d8ef-ad7c-49d2-9f66-0fb87dc4e077, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:41,284 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:41,285 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:43,254 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:43,255 - DEBUG - ydb.connection.1469336  - 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-12 16:08:43,261 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:43,262 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 2c5c5ea0-540a-43f8-a3f7-2cb3ad1d53f1, localhost:17745): creating call state
2025-12-12 16:08:43,262 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 2c5c5ea0-540a-43f8-a3f7-2cb3ad1d53f1, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:43,264 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 2c5c5ea0-540a-43f8-a3f7-2cb3ad1d53f1, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:43,264 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:43,265 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:44,815 - INFO - ydb.tests.library.harness.kikimr_runner.1469336  - stop: Stopped node localhost:17934/5
2025-12-12 16:08:44,815 - DEBUG - ydb.tests.library.fixtures.1469336  - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-12-12 16:08:44,817 - INFO - ya.test.1469336  - pytest_runtest_teardown: Test teardown
2025-12-12 16:08:44,817 - DEBUG - ydb.table.SessionPool.1469336  - stop: Requested session pool stop.
2025-12-12 16:08:44,818 - DEBUG - ydb.table.SessionPool.1469336  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:08:44,818 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroying sessions in active queue
2025-12-12 16:08:44,818 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4c770c33f0>, reason: session-pool-terminated
2025-12-12 16:08:44,819 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Session <ydb.table.Session object at 0x7f4c770c33f0> is no longer active. Current active count 0.
2025-12-12 16:08:44,819 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(DeleteSession, 8f3fe70b-d494-47fe-bd48-cfe4fe250a41, ghrun-jumawwbfgm.auto.internal:17934): creating call state
2025-12-12 16:08:44,820 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(DeleteSession, 8f3fe70b-d494-47fe-bd48-cfe4fe250a41, ghrun-jumawwbfgm.auto.internal:17934): request = { session_id: "ydb://session/3?node_id=50004&id=ZGZhODY4NDYtZTg4YzU2MDgtODg5MGE4ZTItMzlhYzAyNzQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:08:44,818 - DEBUG - ydb.table.SessionPool.1469336  - _terminate_event: Terminated session pool.
2025-12-12 16:08:44,821 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(DeleteSession, 8f3fe70b-d494-47fe-bd48-cfe4fe250a41, ghrun-jumawwbfgm.auto.internal:17934): on response callback started
2025-12-12 16:08:44,822 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 8f3fe70b-d494-47fe-bd48-cfe4fe250a41, ghrun-jumawwbfgm.auto.internal:17934): 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:17934: 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:17934: 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-12T16:08:44.820555297+00:00", grpc_status:14}"
>
2025-12-12 16:08:44,822 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 8f3fe70b-d494-47fe-bd48-cfe4fe250a41, ghrun-jumawwbfgm.auto.internal:17934): unhandled rpc error, disconnecting channel
2025-12-12 16:08:44,822 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:17934
2025-12-12 16:08:44,823 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4c770c33f0>
2025-12-12 16:08:44,823 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroyed active sessions
2025-12-12 16:08:44,823 - DEBUG - ydb.table.SessionPool.1469336  - stop: Requested session pool stop.
2025-12-12 16:08:44,823 - DEBUG - ydb.table.SessionPool.1469336  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:08:44,824 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroying sessions in active queue
2025-12-12 16:08:44,824 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4c763a9460>, reason: session-pool-terminated
2025-12-12 16:08:44,824 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Session <ydb.table.Session object at 0x7f4c763a9460> is no longer active. Current active count 0.
2025-12-12 16:08:44,825 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(DeleteSession, 88533937-b466-4776-a5df-c2ac31f75603, ghrun-jumawwbfgm.auto.internal:63259): creating call state
2025-12-12 16:08:44,825 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(DeleteSession, 88533937-b466-4776-a5df-c2ac31f75603, ghrun-jumawwbfgm.auto.internal:63259): request = { session_id: "ydb://session/3?node_id=50003&id=MmFmYzU3NjItODBlNTc4Mi1jMzcxZmYyLWUwNDZlZDNl" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:08:44,826 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:44,824 - DEBUG - ydb.table.SessionPool.1469336  - _terminate_event: Terminated session pool.
2025-12-12 16:08:44,828 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(DeleteSession, 88533937-b466-4776-a5df-c2ac31f75603, ghrun-jumawwbfgm.auto.internal:63259): on response callback started
2025-12-12 16:08:44,827 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4c763a9460>
2025-12-12 16:08:44,828 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 88533937-b466-4776-a5df-c2ac31f75603, ghrun-jumawwbfgm.auto.internal:63259): 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:63259: 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:63259: 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-12T16:08:44.826252772+00:00", grpc_status:14}"
>
2025-12-12 16:08:44,829 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 88533937-b466-4776-a5df-c2ac31f75603, ghrun-jumawwbfgm.auto.internal:63259): unhandled rpc error, disconnecting channel
2025-12-12 16:08:44,829 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:63259
2025-12-12 16:08:44,830 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:44,830 - DEBUG - ydb.connection.1469336  - 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-12 16:08:44,826 - DEBUG - ydb.connection.1469336  - 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-12 16:08:44,829 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroyed active sessions
2025-12-12 16:08:44,838 - DEBUG - ydb.table.SessionPool.1469336  - stop: Requested session pool stop.
2025-12-12 16:08:44,839 - DEBUG - ydb.table.SessionPool.1469336  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:08:44,840 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroying sessions in active queue
2025-12-12 16:08:44,840 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4c7285a5e0>, reason: session-pool-terminated
2025-12-12 16:08:44,840 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Session <ydb.table.Session object at 0x7f4c7285a5e0> is no longer active. Current active count 0.
2025-12-12 16:08:44,840 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(DeleteSession, 70dd587e-170e-4522-aa1d-ae1031f61121, ghrun-jumawwbfgm.auto.internal:10743): creating call state
2025-12-12 16:08:44,841 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(DeleteSession, 70dd587e-170e-4522-aa1d-ae1031f61121, ghrun-jumawwbfgm.auto.internal:10743): request = { session_id: "ydb://session/3?node_id=50002&id=MzM0NmY5M2ItOWIyZTE0ZmEtNzFlODQzYjctMWRmZGJiN2E=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:08:44,842 - DEBUG - ydb.table.SessionPool.1469336  - _terminate_event: Terminated session pool.
2025-12-12 16:08:44,842 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(DeleteSession, 70dd587e-170e-4522-aa1d-ae1031f61121, ghrun-jumawwbfgm.auto.internal:10743): on response callback started
2025-12-12 16:08:44,844 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 70dd587e-170e-4522-aa1d-ae1031f61121, ghrun-jumawwbfgm.auto.internal:10743): 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:10743: 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:10743: Failed to connect to remote host: Connection refused {grpc_status:14, created_time:"2025-12-12T16:08:44.841887977+00:00", file_line:153, file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc"}"
>
2025-12-12 16:08:44,846 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 70dd587e-170e-4522-aa1d-ae1031f61121, ghrun-jumawwbfgm.auto.internal:10743): unhandled rpc error, disconnecting channel
2025-12-12 16:08:44,846 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:10743
2025-12-12 16:08:44,846 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:44,847 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4c7285a5e0>
2025-12-12 16:08:44,847 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:44,848 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 0917f61e-ae0b-472b-a38e-4a9196e26d0d, localhost:17745): creating call state
2025-12-12 16:08:44,848 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroyed active sessions
2025-12-12 16:08:44,849 - DEBUG - ydb.connection.1469336  - 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-12 16:08:44,849 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 0917f61e-ae0b-472b-a38e-4a9196e26d0d, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:44,850 - DEBUG - ydb.table.SessionPool.1469336  - stop: Requested session pool stop.
2025-12-12 16:08:44,853 - DEBUG - ydb.table.SessionPool.1469336  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:08:44,854 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroying sessions in active queue
2025-12-12 16:08:44,854 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4c72ddb0d0>, reason: session-pool-terminated
2025-12-12 16:08:44,854 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Session <ydb.table.Session object at 0x7f4c72ddb0d0> is no longer active. Current active count 0.
2025-12-12 16:08:44,854 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(DeleteSession, 9f2fac54-0271-4dbc-9d33-e866ef579ef3, ghrun-jumawwbfgm.auto.internal:25021): creating call state
2025-12-12 16:08:44,855 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(DeleteSession, 9f2fac54-0271-4dbc-9d33-e866ef579ef3, ghrun-jumawwbfgm.auto.internal:25021): request = { session_id: "ydb://session/3?node_id=50001&id=NTE2NjI0MWItN2VhZGZkNTctZTRlMGNiZjQtMTdkNjllOWY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:08:44,856 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 0917f61e-ae0b-472b-a38e-4a9196e26d0d, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:44,858 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:44,857 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:44,856 - DEBUG - ydb.table.SessionPool.1469336  - _terminate_event: Terminated session pool.
2025-12-12 16:08:44,858 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:44,860 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:44,859 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, 47e0b80f-3d37-41ff-8beb-5205f62f127b, localhost:17745): creating call state
2025-12-12 16:08:44,860 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(DeleteSession, 9f2fac54-0271-4dbc-9d33-e866ef579ef3, ghrun-jumawwbfgm.auto.internal:25021): on response callback started
2025-12-12 16:08:44,862 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 9f2fac54-0271-4dbc-9d33-e866ef579ef3, ghrun-jumawwbfgm.auto.internal:25021): 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:25021: 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:25021: 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-12T16:08:44.857800272+00:00", grpc_status:14}"
>
2025-12-12 16:08:44,862 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, 47e0b80f-3d37-41ff-8beb-5205f62f127b, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:44,862 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, e3019bab-e345-45bf-a39f-818a9be283d3, localhost:17745): creating call state
2025-12-12 16:08:44,865 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, e3019bab-e345-45bf-a39f-818a9be283d3, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:44,863 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 9f2fac54-0271-4dbc-9d33-e866ef579ef3, ghrun-jumawwbfgm.auto.internal:25021): unhandled rpc error, disconnecting channel
2025-12-12 16:08:44,866 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:25021
2025-12-12 16:08:44,866 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4c72ddb0d0>
2025-12-12 16:08:44,866 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroyed active sessions
2025-12-12 16:08:44,867 - DEBUG - ydb.table.SessionPool.1469336  - stop: Requested session pool stop.
2025-12-12 16:08:44,867 - DEBUG - ydb.table.SessionPool.1469336  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-12 16:08:44,867 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroying sessions in active queue
2025-12-12 16:08:44,867 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4c76345b40>, reason: session-pool-terminated
2025-12-12 16:08:44,867 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Session <ydb.table.Session object at 0x7f4c76345b40> is no longer active. Current active count 0.
2025-12-12 16:08:44,867 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(DeleteSession, 3d403c76-3fed-4cfe-a444-2044fb7c063d, ghrun-jumawwbfgm.auto.internal:3219): creating call state
2025-12-12 16:08:44,868 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(DeleteSession, 3d403c76-3fed-4cfe-a444-2044fb7c063d, ghrun-jumawwbfgm.auto.internal:3219): request = { session_id: "ydb://session/3?node_id=50000&id=OWNiMDEzMWMtNjRjZDE0MmMtZDIxYzZkNmUtY2U0MDFjYzA=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-12 16:08:44,868 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:44,869 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, e3019bab-e345-45bf-a39f-818a9be283d3, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:44,870 - DEBUG - ydb.connection.1469336  - 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-12 16:08:44,874 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:44,870 - DEBUG - ydb.table.SessionPool.1469336  - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4c76345b40>
2025-12-12 16:08:44,871 - DEBUG - ydb.connection.1469336  - _on_response_callback: RpcState(DeleteSession, 3d403c76-3fed-4cfe-a444-2044fb7c063d, ghrun-jumawwbfgm.auto.internal:3219): on response callback started
2025-12-12 16:08:44,873 - DEBUG - ydb.table.SessionPool.1469336  - _terminate_event: Terminated session pool.
2025-12-12 16:08:44,870 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, 47e0b80f-3d37-41ff-8beb-5205f62f127b, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:44,882 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:44,883 - DEBUG - ydb.table.SessionPool.1469336  - stop: Destroyed active sessions
2025-12-12 16:08:44,884 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 3d403c76-3fed-4cfe-a444-2044fb7c063d, ghrun-jumawwbfgm.auto.internal:3219): 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:3219: 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:3219: 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-12T16:08:44.869104183+00:00", grpc_status:14}"
>
2025-12-12 16:08:44,886 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:44,887 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:44,887 - DEBUG - ydb.topic.1469336  - close: Close topic client
2025-12-12 16:08:44,889 - DEBUG - ydb.topic.1469336  - close: Topic client was closed
2025-12-12 16:08:44,889 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:44,889 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, eb0e7fe8-41ab-453e-830a-3b61e3c54c70, localhost:17745): creating call state
2025-12-12 16:08:44,888 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:44,888 - DEBUG - ydb.connection.1469336  - _rpc_error_handler: RpcState(DeleteSession, 3d403c76-3fed-4cfe-a444-2044fb7c063d, ghrun-jumawwbfgm.auto.internal:3219): unhandled rpc error, disconnecting channel
2025-12-12 16:08:44,890 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, eb0e7fe8-41ab-453e-830a-3b61e3c54c70, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:44,891 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint ghrun-jumawwbfgm.auto.internal:3219
2025-12-12 16:08:44,892 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 16:08:44,893 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, eb0e7fe8-41ab-453e-830a-3b61e3c54c70, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:44,894 - DEBUG - ydb.connection.1469336  - 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-12 16:08:44,894 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:44,897 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:44,907 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-12-12 16:08:44,907 - DEBUG - ydb.connection.1469336  - _prepare_call: RpcState(ListEndpoints, a093aceb-92d1-4861-ab92-85e212f48c2e, localhost:17745): creating call state
2025-12-12 16:08:44,908 - DEBUG - ydb.connection.1469336  - _log_request: RpcState(ListEndpoints, a093aceb-92d1-4861-ab92-85e212f48c2e, localhost:17745): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-12-12 16:08:44,910 - DEBUG - ydb.connection.1469336  - _log_response: RpcState(ListEndpoints, a093aceb-92d1-4861-ab92-85e212f48c2e, localhost:17745): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-12 16:08:44,910 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1469336  - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:17745". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-12 16:08:44,911 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:45,061 - INFO - ydb.pool.Discovery.1469336  - run: Successfully terminated discovery process
2025-12-12 16:08:45,062 - DEBUG - ydb.topic.1469336  - close: Close topic client
2025-12-12 16:08:45,062 - DEBUG - ydb.topic.1469336  - close: Topic client was closed
2025-12-12 16:08:45,063 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:45,063 - INFO - ydb.pool.Discovery.1469336  - run: Successfully terminated discovery process
2025-12-12 16:08:45,064 - DEBUG - ydb.topic.1469336  - close: Close topic client
2025-12-12 16:08:45,064 - DEBUG - ydb.topic.1469336  - close: Topic client was closed
2025-12-12 16:08:45,065 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:45,065 - INFO - ydb.pool.Discovery.1469336  - run: Successfully terminated discovery process
2025-12-12 16:08:45,065 - DEBUG - ydb.topic.1469336  - close: Close topic client
2025-12-12 16:08:45,066 - DEBUG - ydb.topic.1469336  - close: Topic client was closed
2025-12-12 16:08:45,066 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:45,089 - INFO - ydb.pool.Discovery.1469336  - run: Successfully terminated discovery process
2025-12-12 16:08:45,089 - DEBUG - ydb.topic.1469336  - close: Close topic client
2025-12-12 16:08:45,090 - DEBUG - ydb.topic.1469336  - close: Topic client was closed
2025-12-12 16:08:45,090 - DEBUG - ydb.connection.1469336  - close: Closing channel for endpoint localhost:17745
2025-12-12 16:08:45,110 - INFO - ydb.pool.Discovery.1469336  - run: Successfully terminated discovery process
