2025-10-27 08:55:17,142 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 08:55:17,143 - INFO - ya.test - pytest_runtest_setup: test_stop_start[enable_alter_database_create_hive_first--true]
2025-10-27 08:55:17,143 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 08:55:17,149 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-27 08:55:17,264 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd
2025-10-27 08:55:17,279 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17030
2025-10-27 08:55:17,281 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_tenants
2025-10-27 08:55:17,284 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-10-27 08:55:17,285 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/xykr/000392/r3tmp/kikimr_cluster_tte2loof
2025-10-27 08:55:17,327 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd
2025-10-27 08:55:17,350 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9045
2025-10-27 08:55:17,351 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17836
2025-10-27 08:55:17,354 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25417
2025-10-27 08:55:17,355 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5863
2025-10-27 08:55:17,358 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:55:17,358 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/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/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_d3tmh6g_.log --grpc-port=9045 --mon-port=17836 --ic-port=17030 --module=1
2025-10-27 08:55:17,358 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:9045/1, disk_size 68719476736
2025-10-27 08:55:17,359 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:55:17,360 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/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/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_d3tmh6g_.log --grpc-port=9045 --mon-port=17836 --ic-port=17030 --module=1
2025-10-27 08:55:17,361 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/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/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_d3tmh6g_.log', '--grpc-port=9045', '--mon-port=17836', '--ic-port=17030', '--module=1')' in '/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-10-27 08:55:17,364 - DEBUG - ya.test - execute: Command pid: 3713523
2025-10-27 08:55:17,366 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:9045/1
2025-10-27 08:55:17,367 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-27 08:55:17,369 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-27 08:55:17,382 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 08:55:17,385 - DEBUG - ya.test - execute: Command pid: 3713525
2025-10-27 08:55:17,867 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) rc: 0
2025-10-27 08:55:17,868 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) elapsed time (sec): 0.4788036346435547
2025-10-27 08:55:17,868 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) idrss: 0
2025-10-27 08:55:17,868 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) inblock: 0
2025-10-27 08:55:17,868 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) isrss: 0
2025-10-27 08:55:17,868 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) ixrss: 0
2025-10-27 08:55:17,869 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) majflt: 0
2025-10-27 08:55:17,869 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) maxrss: 540192
2025-10-27 08:55:17,869 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) minflt: 16125
2025-10-27 08:55:17,869 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) msgrcv: 0
2025-10-27 08:55:17,869 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) msgsnd: 0
2025-10-27 08:55:17,869 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) nivcsw: 96
2025-10-27 08:55:17,870 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) nsignals: 0
2025-10-27 08:55:17,870 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) nswap: 0
2025-10-27 08:55:17,870 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) nvcsw: 14
2025-10-27 08:55:17,871 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) oublock: 8
2025-10-27 08:55:17,872 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) stime: 0.26852699999999996
2025-10-27 08:55:17,872 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) utime: 0.112859
2025-10-27 08:55:17,872 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) wtime: 0.481
2025-10-27 08:55:17,872 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) output:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9

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

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

2025-10-27 08:55:17,873 - DEBUG - ya.test - _save_outputs: Command (pid 3713525) errors:

2025-10-27 08:55:17,873 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9
2025-10-27 08:55:20,040 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd
2025-10-27 08:55:20,042 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9045', '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: 17030\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-27 08:55:20,044 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-27 08:55:20,046 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-27 08:55:20,046 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9045', '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: 17030\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-27 08:55:20,047 - DEBUG - ya.test - execute: Command pid: 3714964
2025-10-27 08:55:20,502 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) rc: 0
2025-10-27 08:55:20,502 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) elapsed time (sec): 0.4537928104400635
2025-10-27 08:55:20,502 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) idrss: 0
2025-10-27 08:55:20,502 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) inblock: 0
2025-10-27 08:55:20,503 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) isrss: 0
2025-10-27 08:55:20,503 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) ixrss: 0
2025-10-27 08:55:20,503 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) majflt: 0
2025-10-27 08:55:20,503 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) maxrss: 548452
2025-10-27 08:55:20,503 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) minflt: 18271
2025-10-27 08:55:20,503 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) msgrcv: 0
2025-10-27 08:55:20,503 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) msgsnd: 0
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) nivcsw: 115
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) nsignals: 0
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) nswap: 0
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) nvcsw: 121
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) oublock: 8
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) stime: 0.252639
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) utime: 0.128324
2025-10-27 08:55:20,504 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) wtime: 0.455
2025-10-27 08:55:20,505 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-27 08:55:20,505 - DEBUG - ya.test - _save_outputs: Command (pid 3714964) errors:

2025-10-27 08:55:20,507 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd
2025-10-27 08:55:20,508 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9045', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-27 08:55:20,509 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-27 08:55:20,510 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-27 08:55:20,510 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:9045', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-27 08:55:20,511 - DEBUG - ya.test - execute: Command pid: 3715179
2025-10-27 08:55:21,013 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) rc: 0
2025-10-27 08:55:21,014 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) elapsed time (sec): 0.5012991428375244
2025-10-27 08:55:21,014 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) idrss: 0
2025-10-27 08:55:21,014 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) inblock: 0
2025-10-27 08:55:21,014 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) isrss: 0
2025-10-27 08:55:21,015 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) ixrss: 0
2025-10-27 08:55:21,015 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) majflt: 0
2025-10-27 08:55:21,015 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) maxrss: 548440
2025-10-27 08:55:21,015 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) minflt: 18272
2025-10-27 08:55:21,015 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) msgrcv: 0
2025-10-27 08:55:21,015 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) msgsnd: 0
2025-10-27 08:55:21,015 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) nivcsw: 99
2025-10-27 08:55:21,016 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) nsignals: 0
2025-10-27 08:55:21,016 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) nswap: 0
2025-10-27 08:55:21,016 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) nvcsw: 109
2025-10-27 08:55:21,016 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) oublock: 8
2025-10-27 08:55:21,016 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) stime: 0.264364
2025-10-27 08:55:21,016 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) utime: 0.126298
2025-10-27 08:55:21,017 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) wtime: 0.502
2025-10-27 08:55:21,017 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-27 08:55:21,017 - DEBUG - ya.test - _save_outputs: Command (pid 3715179) errors:

2025-10-27 08:55:21,017 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-27 08:55:21,042 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-27 08:55:21,059 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_tenants.py::TestTenants, test_name: test_stop_start[enable_alter_database_create_hive_first--true])
2025-10-27 08:55:21,063 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:55:21,063 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-10-27 08:55:21,067 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _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-10-27 08:55:21,067 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-10-27 08:55:21,571 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-10-27 08:55:21,571 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-10-27 08:55:21,579 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-10-27 08:55:21,579 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-10-27 08:55:22,094 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-10-27 08:55:22,095 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd
2025-10-27 08:55:22,095 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14758
2025-10-27 08:55:22,096 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18778
2025-10-27 08:55:22,096 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15966
2025-10-27 08:55:22,096 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15700
2025-10-27 08:55:22,097 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19221
2025-10-27 08:55:22,097 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:55:22,098 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:9045 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_lxg6mrnr.log --grpc-port=14758 --mon-port=18778 --ic-port=15966
2025-10-27 08:55:22,098 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:55:22,099 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:9045 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_lxg6mrnr.log --grpc-port=14758 --mon-port=18778 --ic-port=15966
2025-10-27 08:55:22,099 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/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:9045', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_lxg6mrnr.log', '--grpc-port=14758', '--mon-port=18778', '--ic-port=15966')' in '/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-10-27 08:55:22,117 - DEBUG - ya.test - execute: Command pid: 3716294
2025-10-27 08:55:22,118 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14758/1
2025-10-27 08:55:22,119 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-27 08:55:22,119 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-27 08:55:22,120 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 08:55:22,120 - DEBUG - ya.test - execute: Command pid: 3716298
2025-10-27 08:55:22,621 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) rc: 0
2025-10-27 08:55:22,621 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) elapsed time (sec): 0.4965510368347168
2025-10-27 08:55:22,621 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) idrss: 0
2025-10-27 08:55:22,621 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) inblock: 0
2025-10-27 08:55:22,623 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) isrss: 0
2025-10-27 08:55:22,623 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) ixrss: 0
2025-10-27 08:55:22,623 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) majflt: 0
2025-10-27 08:55:22,623 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) maxrss: 540192
2025-10-27 08:55:22,623 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) minflt: 16341
2025-10-27 08:55:22,624 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) msgrcv: 0
2025-10-27 08:55:22,624 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) msgsnd: 0
2025-10-27 08:55:22,624 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) nivcsw: 99
2025-10-27 08:55:22,625 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) nsignals: 0
2025-10-27 08:55:22,625 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) nswap: 0
2025-10-27 08:55:22,630 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) nvcsw: 14
2025-10-27 08:55:22,630 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) oublock: 8
2025-10-27 08:55:22,631 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) stime: 0.253449
2025-10-27 08:55:22,633 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) utime: 0.112499
2025-10-27 08:55:22,639 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) wtime: 0.498
2025-10-27 08:55:22,643 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) output:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9

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

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

2025-10-27 08:55:22,643 - DEBUG - ya.test - _save_outputs: Command (pid 3716298) errors:

2025-10-27 08:55:22,643 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9
2025-10-27 08:55:22,644 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-10-27 08:55:24,662 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-10-27 08:55:24,663 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:55:24,677 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 08:55:24,679 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-27 08:55:24,680 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:55:24,680 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f16951cc8d0 state=pending>
2025-10-27 08:55:24,687 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-27 08:55:24,688 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f169364c3d0>
2025-10-27 08:55:24,688 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f169364c3d0>
2025-10-27 08:55:24,695 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-27 08:55:24,698 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:55:24,698 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 845c0790-3483-4aa6-a19c-f5f133cb1f02, localhost:9045): creating call state
2025-10-27 08:55:24,699 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 845c0790-3483-4aa6-a19c-f5f133cb1f02, localhost:9045): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-10-27 08:55:24,702 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 845c0790-3483-4aa6-a19c-f5f133cb1f02, localhost:9045): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-aathosbtb4.auto.internal" port: 14758 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-10-27 08:55:24,702 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _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-aathosbtb4.auto.internal:14758, location , ssl: False>]>
2025-10-27 08:55:24,703 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:55:24,718 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:55:24,719 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f169364c3d0>
2025-10-27 08:55:24,720 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ed85d469-68aa-45b0-ba69-46d37b663e57, ghrun-aathosbtb4.auto.internal:14758): creating call state
2025-10-27 08:55:24,722 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ed85d469-68aa-45b0-ba69-46d37b663e57, ghrun-aathosbtb4.auto.internal:14758): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-27 08:55:24,728 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ed85d469-68aa-45b0-ba69-46d37b663e57, ghrun-aathosbtb4.auto.internal:14758): on response callback started
2025-10-27 08:55:24,728 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ed85d469-68aa-45b0-ba69-46d37b663e57, ghrun-aathosbtb4.auto.internal:14758): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YTY0NzFmM2ItNjdkODM1YjMtMzYyYmQ3NWQtY2UwMDNjZmI=" } } } }
2025-10-27 08:55:24,729 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f169364c3d0>
2025-10-27 08:55:24,730 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f169364c3d0>
2025-10-27 08:55:24,730 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ed85d469-68aa-45b0-ba69-46d37b663e57, ghrun-aathosbtb4.auto.internal:14758): on response callback success
2025-10-27 08:55:24,730 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 79bc804f-1340-4e12-be24-bafa8dc405de, ghrun-aathosbtb4.auto.internal:14758): creating call state
2025-10-27 08:55:24,731 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 79bc804f-1340-4e12-be24-bafa8dc405de, ghrun-aathosbtb4.auto.internal:14758): request = { session_id: "ydb://session/3?node_id=50000&id=YTY0NzFmM2ItNjdkODM1YjMtMzYyYmQ3NWQtY2UwMDNjZmI=" yql_text: "\n                CREATE TABLE `table_0` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-27 08:55:24,790 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 79bc804f-1340-4e12-be24-bafa8dc405de, ghrun-aathosbtb4.auto.internal:14758): response = { operation { ready: true status: SUCCESS } }
2025-10-27 08:55:24,794 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f169364c3d0>
2025-10-27 08:55:24,795 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-10-27 08:55:24,796 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:55:24,796 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-10-27 08:55:24,806 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _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=1761555324804853&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-27 08:55:24,807 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-10-27 08:55:25,315 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-10-27 08:55:25,315 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-10-27 08:55:25,322 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-10-27 08:55:25,323 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-10-27 08:55:42,325 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:14758/1
2025-10-27 08:55:42,326 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-10-27 08:55:42,326 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:55:42,326 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-10-27 08:55:42,330 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _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-10-27 08:55:42,331 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-10-27 08:55:42,839 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-10-27 08:55:42,843 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-10-27 08:55:42,849 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-10-27 08:55:42,851 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-10-27 08:55:43,368 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-10-27 08:55:43,369 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd
2025-10-27 08:55:43,369 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15680
2025-10-27 08:55:43,371 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3645
2025-10-27 08:55:43,372 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2351
2025-10-27 08:55:43,372 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25939
2025-10-27 08:55:43,372 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61550
2025-10-27 08:55:43,374 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:55:43,374 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:9045 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_bsxwn654.log --grpc-port=15680 --mon-port=3645 --ic-port=2351
2025-10-27 08:55:43,375 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:55:43,376 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:9045 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_bsxwn654.log --grpc-port=15680 --mon-port=3645 --ic-port=2351
2025-10-27 08:55:43,380 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/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:9045', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_bsxwn654.log', '--grpc-port=15680', '--mon-port=3645', '--ic-port=2351')' in '/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-10-27 08:55:43,388 - DEBUG - ya.test - execute: Command pid: 3727992
2025-10-27 08:55:43,389 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:15680/2
2025-10-27 08:55:43,390 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-10-27 08:55:43,390 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-10-27 08:55:43,390 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 08:55:43,397 - DEBUG - ya.test - execute: Command pid: 3727998
2025-10-27 08:55:44,007 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) rc: 0
2025-10-27 08:55:44,007 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) elapsed time (sec): 0.6003518104553223
2025-10-27 08:55:44,008 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) idrss: 0
2025-10-27 08:55:44,008 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) inblock: 0
2025-10-27 08:55:44,008 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) isrss: 0
2025-10-27 08:55:44,008 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) ixrss: 0
2025-10-27 08:55:44,008 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) majflt: 0
2025-10-27 08:55:44,008 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) maxrss: 540192
2025-10-27 08:55:44,009 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) minflt: 16126
2025-10-27 08:55:44,009 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) msgrcv: 0
2025-10-27 08:55:44,009 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) msgsnd: 0
2025-10-27 08:55:44,009 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) nivcsw: 151
2025-10-27 08:55:44,009 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) nsignals: 0
2025-10-27 08:55:44,009 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) nswap: 0
2025-10-27 08:55:44,010 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) nvcsw: 14
2025-10-27 08:55:44,010 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) oublock: 8
2025-10-27 08:55:44,010 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) stime: 0.257229
2025-10-27 08:55:44,010 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) utime: 0.120476
2025-10-27 08:55:44,010 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) wtime: 0.611
2025-10-27 08:55:44,011 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) output:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9

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

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

2025-10-27 08:55:44,011 - DEBUG - ya.test - _save_outputs: Command (pid 3727998) errors:

2025-10-27 08:55:44,023 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9
2025-10-27 08:55:44,026 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-10-27 08:55:46,040 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-10-27 08:55:46,040 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:55:46,043 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 08:55:46,043 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:55:46,046 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-27 08:55:46,046 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f169362acc0 state=pending>
2025-10-27 08:55:46,046 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-27 08:55:46,046 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f169362aa90>
2025-10-27 08:55:46,047 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f169362aa90>
2025-10-27 08:55:46,047 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-27 08:55:46,070 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:55:46,070 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, e76f5a38-03b6-4baf-a81c-c8cfd3eb42cc, localhost:9045): creating call state
2025-10-27 08:55:46,071 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, e76f5a38-03b6-4baf-a81c-c8cfd3eb42cc, localhost:9045): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-10-27 08:55:46,077 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, e76f5a38-03b6-4baf-a81c-c8cfd3eb42cc, localhost:9045): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-aathosbtb4.auto.internal" port: 15680 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50001 } self_location: "1" } } } }
2025-10-27 08:55:46,082 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _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-aathosbtb4.auto.internal:15680, location , ssl: False>]>
2025-10-27 08:55:46,083 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:55:46,094 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:55:46,094 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f169362aa90>
2025-10-27 08:55:46,102 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4d76f7a6-9045-41b8-88bb-af85ce32df6a, ghrun-aathosbtb4.auto.internal:15680): creating call state
2025-10-27 08:55:46,104 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4d76f7a6-9045-41b8-88bb-af85ce32df6a, ghrun-aathosbtb4.auto.internal:15680): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-27 08:55:46,114 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4d76f7a6-9045-41b8-88bb-af85ce32df6a, ghrun-aathosbtb4.auto.internal:15680): on response callback started
2025-10-27 08:55:46,115 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4d76f7a6-9045-41b8-88bb-af85ce32df6a, ghrun-aathosbtb4.auto.internal:15680): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=ODg2Mzk0NDctODM2MTM2YjMtNzBhY2IyMmQtYTEwMmE1NzA=" } } } }
2025-10-27 08:55:46,116 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 4d76f7a6-9045-41b8-88bb-af85ce32df6a, ghrun-aathosbtb4.auto.internal:15680): on response callback success
2025-10-27 08:55:46,116 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f169362aa90>
2025-10-27 08:55:46,116 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f169362aa90>
2025-10-27 08:55:46,117 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, bec175fb-7270-4045-b21f-8a6426ff9158, ghrun-aathosbtb4.auto.internal:15680): creating call state
2025-10-27 08:55:46,117 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, bec175fb-7270-4045-b21f-8a6426ff9158, ghrun-aathosbtb4.auto.internal:15680): request = { session_id: "ydb://session/3?node_id=50001&id=ODg2Mzk0NDctODM2MTM2YjMtNzBhY2IyMmQtYTEwMmE1NzA=" yql_text: "\n                CREATE TABLE `table_1` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-27 08:55:46,255 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, bec175fb-7270-4045-b21f-8a6426ff9158, ghrun-aathosbtb4.auto.internal:15680): response = { operation { ready: true status: SUCCESS } }
2025-10-27 08:55:46,276 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f169362aa90>
2025-10-27 08:55:46,277 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-10-27 08:55:46,277 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:55:46,277 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-10-27 08:55:46,287 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _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=1761555346280265&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-27 08:55:46,288 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-10-27 08:55:46,793 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-10-27 08:55:46,794 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-10-27 08:55:46,797 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-10-27 08:55:46,797 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-10-27 08:56:03,806 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:15680/2
2025-10-27 08:56:03,815 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-10-27 08:56:03,816 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:56:03,816 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-10-27 08:56:03,831 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _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-10-27 08:56:03,832 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-10-27 08:56:04,352 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-10-27 08:56:04,373 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-10-27 08:56:04,379 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-10-27 08:56:04,416 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-10-27 08:56:04,961 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-10-27 08:56:04,990 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd
2025-10-27 08:56:04,990 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12536
2025-10-27 08:56:04,991 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22288
2025-10-27 08:56:04,991 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9747
2025-10-27 08:56:04,993 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30975
2025-10-27 08:56:04,993 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10636
2025-10-27 08:56:05,011 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:56:05,013 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:9045 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_ethxpwrz.log --grpc-port=12536 --mon-port=22288 --ic-port=9747
2025-10-27 08:56:05,018 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-27 08:56:05,018 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:9045 --tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_ethxpwrz.log --grpc-port=12536 --mon-port=22288 --ic-port=9747
2025-10-27 08:56:05,019 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/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:9045', '--tenant=/Root/test_stop_start_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_ethxpwrz.log', '--grpc-port=12536', '--mon-port=22288', '--ic-port=9747')' in '/home/runner/.ya/build/build_root/xykr/000392/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/test_tenants.py.TestTenants.test_stop_start.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-10-27 08:56:05,038 - DEBUG - ya.test - execute: Command pid: 3741543
2025-10-27 08:56:05,038 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:12536/3
2025-10-27 08:56:05,061 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-10-27 08:56:05,062 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-10-27 08:56:05,064 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/xykr/000392/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 08:56:05,074 - DEBUG - ya.test - execute: Command pid: 3741545
2025-10-27 08:56:05,863 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) rc: 0
2025-10-27 08:56:05,864 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) elapsed time (sec): 0.787175178527832
2025-10-27 08:56:05,865 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) idrss: 0
2025-10-27 08:56:05,865 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) inblock: 0
2025-10-27 08:56:05,865 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) isrss: 0
2025-10-27 08:56:05,867 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) ixrss: 0
2025-10-27 08:56:05,867 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) majflt: 0
2025-10-27 08:56:05,871 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) maxrss: 540192
2025-10-27 08:56:05,873 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) minflt: 16126
2025-10-27 08:56:05,884 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) msgrcv: 0
2025-10-27 08:56:05,884 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) msgsnd: 0
2025-10-27 08:56:05,884 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) nivcsw: 183
2025-10-27 08:56:05,885 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) nsignals: 0
2025-10-27 08:56:05,885 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) nswap: 0
2025-10-27 08:56:05,885 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) nvcsw: 19
2025-10-27 08:56:05,885 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) oublock: 8
2025-10-27 08:56:05,885 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) stime: 0.31946399999999997
2025-10-27 08:56:05,892 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) utime: 0.120477
2025-10-27 08:56:05,892 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) wtime: 0.796
2025-10-27 08:56:05,892 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) output:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9

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

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

2025-10-27 08:56:05,893 - DEBUG - ya.test - _save_outputs: Command (pid 3741545) errors:

2025-10-27 08:56:05,893 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 3 version:
Git info:
    Commit: adb44c074251a1871f952a1fe07a46be1893a61c
    Branch: 
    Author: nfrmtk <nfrmtk.aka.fkfka@gmail.com>
    Summary: Merge 48882a55d21ba4abb146d6a9c2cd1aadf2a909fc into 959c4f93fed7b73b12b83b26b671758583f027f9
2025-10-27 08:56:05,893 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait construction done
2025-10-27 08:56:07,908 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database up
2025-10-27 08:56:07,908 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:56:07,910 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 08:56:07,912 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-27 08:56:07,928 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f1695185460 state=pending>
2025-10-27 08:56:07,928 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-27 08:56:07,928 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f1695185cd0>
2025-10-27 08:56:07,929 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f1695185cd0>
2025-10-27 08:56:07,929 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-27 08:56:07,924 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:56:07,952 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:56:07,959 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 28b6e230-5064-4a5b-97cb-03551c890f34, localhost:9045): creating call state
2025-10-27 08:56:07,959 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 28b6e230-5064-4a5b-97cb-03551c890f34, localhost:9045): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-10-27 08:56:07,968 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 28b6e230-5064-4a5b-97cb-03551c890f34, localhost:9045): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-aathosbtb4.auto.internal" port: 12536 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50002 } self_location: "1" } } } }
2025-10-27 08:56:07,970 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _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-aathosbtb4.auto.internal:12536, location , ssl: False>]>
2025-10-27 08:56:07,971 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:56:07,991 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:56:07,992 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f1695185cd0>
2025-10-27 08:56:07,995 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cf26728d-aa44-43b4-8d20-d0efa1ebc99b, ghrun-aathosbtb4.auto.internal:12536): creating call state
2025-10-27 08:56:07,997 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cf26728d-aa44-43b4-8d20-d0efa1ebc99b, ghrun-aathosbtb4.auto.internal:12536): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-27 08:56:08,006 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cf26728d-aa44-43b4-8d20-d0efa1ebc99b, ghrun-aathosbtb4.auto.internal:12536): on response callback started
2025-10-27 08:56:08,008 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cf26728d-aa44-43b4-8d20-d0efa1ebc99b, ghrun-aathosbtb4.auto.internal:12536): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=MTAwNjIwYjctYjUzODFkMWYtYzNmNzQyMTEtZWEzOGY2MGY=" } } } }
2025-10-27 08:56:08,008 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f1695185cd0>
2025-10-27 08:56:08,009 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f1695185cd0>
2025-10-27 08:56:08,009 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cf26728d-aa44-43b4-8d20-d0efa1ebc99b, ghrun-aathosbtb4.auto.internal:12536): on response callback success
2025-10-27 08:56:08,010 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 2899a43b-92fd-4069-a8fd-a0247b8f76d3, ghrun-aathosbtb4.auto.internal:12536): creating call state
2025-10-27 08:56:08,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 2899a43b-92fd-4069-a8fd-a0247b8f76d3, ghrun-aathosbtb4.auto.internal:12536): request = { session_id: "ydb://session/3?node_id=50002&id=MTAwNjIwYjctYjUzODFkMWYtYzNmNzQyMTEtZWEzOGY2MGY=" yql_text: "\n                CREATE TABLE `table_2` (id utf8, PRIMARY KEY (id));\n            " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-27 08:56:08,245 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 2899a43b-92fd-4069-a8fd-a0247b8f76d3, ghrun-aathosbtb4.auto.internal:12536): response = { operation { ready: true status: SUCCESS } }
2025-10-27 08:56:08,267 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f1695185cd0>
2025-10-27 08:56:08,269 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-10-27 08:56:08,269 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:56:08,275 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-10-27 08:56:08,286 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _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=1761555368278820&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-27 08:56:08,290 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-10-27 08:56:08,801 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-10-27 08:56:08,801 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-10-27 08:56:08,810 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-10-27 08:56:08,822 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: stop nodes
2025-10-27 08:56:30,834 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:12536/3
2025-10-27 08:56:30,834 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: database down
2025-10-27 08:56:30,834 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:56:30,835 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-10-27 08:56:30,884 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _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-10-27 08:56:30,908 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait console operation done
2025-10-27 08:56:31,426 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: console operation done
2025-10-27 08:56:31,438 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-10-27 08:56:31,448 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_stop_start_enable_alter_database_create_hive_first--true_: tenant gone
2025-10-27 08:56:31,449 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_stop_start_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-10-27 08:56:32,370 - ERROR - ya.test - logreport: ydb/tests/functional/tenants/test_tenants.py:297: in test_stop_start
    with ydb_database_ctx(ydb_cluster, database_path):
contrib/tools/python3/Lib/contextlib.py:137: in __enter__
    return next(self.gen)
ydb/tests/library/fixtures/__init__.py:88: in ydb_database_ctx
    ydb_cluster.create_database(database_path, storage_pool_units_count=storage_pools, timeout_seconds=timeout_seconds, token=token)
ydb/tests/library/harness/kikimr_cluster_interface.py:230: in create_database
    raise RuntimeError('create_database failed: %s, %s' % (operation.status, ydb.issues._format_issues(operation.issues)))
E   RuntimeError: create_database failed: 400080, message: "Group fit error BoxId# 1 StoragePoolId# 5 Error# failed to allocate group: no group options PDisks# {[(1:1-S)]}" severity: 1
2025-10-27 08:56:32,375 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-27 08:56:32,378 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-27 08:56:32,379 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-27 08:56:32,380 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-27 08:56:32,383 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-27 08:56:32,386 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f1695185cd0>, reason: session-pool-terminated
2025-10-27 08:56:32,387 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f1695185cd0> is no longer active. Current active count 0.
2025-10-27 08:56:32,387 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, e898b25b-d250-4ffb-a9fe-597f8ffd1d74, ghrun-aathosbtb4.auto.internal:12536): creating call state
2025-10-27 08:56:32,388 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, e898b25b-d250-4ffb-a9fe-597f8ffd1d74, ghrun-aathosbtb4.auto.internal:12536): request = { session_id: "ydb://session/3?node_id=50002&id=MTAwNjIwYjctYjUzODFkMWYtYzNmNzQyMTEtZWEzOGY2MGY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-27 08:56:32,390 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f1695185cd0>
2025-10-27 08:56:32,390 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, e898b25b-d250-4ffb-a9fe-597f8ffd1d74, ghrun-aathosbtb4.auto.internal:12536): on response callback started
2025-10-27 08:56:32,399 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-27 08:56:32,400 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(DeleteSession, e898b25b-d250-4ffb-a9fe-597f8ffd1d74, ghrun-aathosbtb4.auto.internal:12536): 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:12536: 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:12536: Failed to connect to remote host: Connection refused {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-10-27T08:56:32.3891891+00:00", grpc_status:14}"
>
2025-10-27 08:56:32,401 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-27 08:56:32,401 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(DeleteSession, e898b25b-d250-4ffb-a9fe-597f8ffd1d74, ghrun-aathosbtb4.auto.internal:12536): unhandled rpc error, disconnecting channel
2025-10-27 08:56:32,402 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-aathosbtb4.auto.internal:12536
2025-10-27 08:56:32,402 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-27 08:56:32,402 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-27 08:56:32,411 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 08:56:32,427 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-27 08:56:32,428 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:56:32,428 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f169362aa90>, reason: session-pool-terminated
2025-10-27 08:56:32,438 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f169362aa90> is no longer active. Current active count 0.
2025-10-27 08:56:32,438 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c27864d6-3d45-4740-bf9c-b95ab57f8c5a, ghrun-aathosbtb4.auto.internal:15680): creating call state
2025-10-27 08:56:32,439 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c27864d6-3d45-4740-bf9c-b95ab57f8c5a, ghrun-aathosbtb4.auto.internal:15680): request = { session_id: "ydb://session/3?node_id=50001&id=ODg2Mzk0NDctODM2MTM2YjMtNzBhY2IyMmQtYTEwMmE1NzA=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-27 08:56:32,445 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:56:32,446 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 5383ebe3-a868-46e0-b663-3beebac315d4, localhost:9045): creating call state
2025-10-27 08:56:32,447 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 5383ebe3-a868-46e0-b663-3beebac315d4, localhost:9045): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-10-27 08:56:32,450 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, c27864d6-3d45-4740-bf9c-b95ab57f8c5a, ghrun-aathosbtb4.auto.internal:15680): on response callback started
2025-10-27 08:56:32,451 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(DeleteSession, c27864d6-3d45-4740-bf9c-b95ab57f8c5a, ghrun-aathosbtb4.auto.internal:15680): 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:15680: 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:15680: Failed to connect to remote host: Connection refused {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-10-27T08:56:32.439837732+00:00", grpc_status:14}"
>
2025-10-27 08:56:32,452 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(DeleteSession, c27864d6-3d45-4740-bf9c-b95ab57f8c5a, ghrun-aathosbtb4.auto.internal:15680): unhandled rpc error, disconnecting channel
2025-10-27 08:56:32,452 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-aathosbtb4.auto.internal:15680
2025-10-27 08:56:32,452 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f169362aa90>
2025-10-27 08:56:32,453 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-27 08:56:32,453 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-27 08:56:32,453 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-27 08:56:32,453 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-27 08:56:32,454 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f169364c3d0>, reason: session-pool-terminated
2025-10-27 08:56:32,454 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f169364c3d0> is no longer active. Current active count 0.
2025-10-27 08:56:32,454 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 08:56:32,455 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 9f19fa4c-818a-4172-9259-6b04127eeca6, ghrun-aathosbtb4.auto.internal:14758): creating call state
2025-10-27 08:56:32,455 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:56:32,456 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 9f19fa4c-818a-4172-9259-6b04127eeca6, ghrun-aathosbtb4.auto.internal:14758): request = { session_id: "ydb://session/3?node_id=50000&id=YTY0NzFmM2ItNjdkODM1YjMtMzYyYmQ3NWQtY2UwMDNjZmI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-27 08:56:32,453 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-27 08:56:32,461 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f169364c3d0>
2025-10-27 08:56:32,451 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 5383ebe3-a868-46e0-b663-3beebac315d4, localhost:9045): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-10-27 08:56:32,461 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 9f19fa4c-818a-4172-9259-6b04127eeca6, ghrun-aathosbtb4.auto.internal:14758): on response callback started
2025-10-27 08:56:32,487 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(DeleteSession, 9f19fa4c-818a-4172-9259-6b04127eeca6, ghrun-aathosbtb4.auto.internal:14758): 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:14758: 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:14758: Failed to connect to remote host: Connection refused {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-10-27T08:56:32.460182499+00:00", grpc_status:14}"
>
2025-10-27 08:56:32,490 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(DeleteSession, 9f19fa4c-818a-4172-9259-6b04127eeca6, ghrun-aathosbtb4.auto.internal:14758): unhandled rpc error, disconnecting channel
2025-10-27 08:56:32,487 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:9045". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-10-27 08:56:32,466 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-27 08:56:32,491 - DEBUG - ydb.topic - close: Close topic client
2025-10-27 08:56:32,492 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-27 08:56:32,492 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:56:32,490 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-aathosbtb4.auto.internal:14758
2025-10-27 08:56:32,493 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 08:56:32,491 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:56:32,493 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-27 08:56:32,467 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:56:32,498 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, f6a81f97-968d-4b7e-b1cb-21b644a6bea3, localhost:9045): creating call state
2025-10-27 08:56:32,500 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, f6a81f97-968d-4b7e-b1cb-21b644a6bea3, localhost:9045): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-10-27 08:56:32,501 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_
2025-10-27 08:56:32,501 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 20646b32-6755-447a-be9b-7f88ad468209, localhost:9045): creating call state
2025-10-27 08:56:32,502 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 20646b32-6755-447a-be9b-7f88ad468209, localhost:9045): request = { database: "/Root/test_stop_start_enable_alter_database_create_hive_first--true_" }
2025-10-27 08:56:32,503 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, f6a81f97-968d-4b7e-b1cb-21b644a6bea3, localhost:9045): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-10-27 08:56:32,504 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:9045". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-10-27 08:56:32,504 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:56:32,504 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 20646b32-6755-447a-be9b-7f88ad468209, localhost:9045): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-10-27 08:56:32,505 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to resolve endpoints for database /Root/test_stop_start_enable_alter_database_create_hive_first--true_. Endpoint: "localhost:9045". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-10-27 08:56:32,505 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:56:32,638 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-27 08:56:32,638 - DEBUG - ydb.topic - close: Close topic client
2025-10-27 08:56:32,639 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-27 08:56:32,639 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:56:32,670 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-27 08:56:32,671 - DEBUG - ydb.topic - close: Close topic client
2025-10-27 08:56:32,671 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-27 08:56:32,671 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9045
2025-10-27 08:56:32,702 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-27 08:56:32,703 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_tenants
2025-10-27 08:56:39,714 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:9045/1
2025-10-27 08:56:39,717 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10636
2025-10-27 08:56:39,718 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30975
2025-10-27 08:56:39,718 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9747
2025-10-27 08:56:39,726 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22288
2025-10-27 08:56:39,726 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12536
2025-10-27 08:56:39,726 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61550
2025-10-27 08:56:39,726 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25939
2025-10-27 08:56:39,727 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2351
2025-10-27 08:56:39,727 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3645
2025-10-27 08:56:39,727 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15680
2025-10-27 08:56:39,727 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19221
2025-10-27 08:56:39,727 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15700
2025-10-27 08:56:39,728 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15966
2025-10-27 08:56:39,728 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18778
2025-10-27 08:56:39,734 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14758
2025-10-27 08:56:39,734 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5863
2025-10-27 08:56:39,734 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25417
2025-10-27 08:56:39,734 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17836
2025-10-27 08:56:39,736 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9045
2025-10-27 08:56:39,736 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17030
