2025-11-01 19:59:37,905 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 19:59:37,905 - INFO - ya.test - pytest_runtest_setup: test_create_tables[enable_alter_database_create_hive_first--true]
2025-11-01 19:59:37,905 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 19:59:37,905 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 19:59:37,906 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_tenants
2025-11-01 19:59:44,912 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:16480/1
2025-11-01 19:59:44,913 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64029
2025-11-01 19:59:44,913 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2714
2025-11-01 19:59:44,913 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10347
2025-11-01 19:59:44,918 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15127
2025-11-01 19:59:44,918 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21716
2025-11-01 19:59:44,918 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29810
2025-11-01 19:59:44,918 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8542
2025-11-01 19:59:44,918 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8638
2025-11-01 19:59:44,919 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16480
2025-11-01 19:59:44,919 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14296
2025-11-01 19:59:45,070 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd
2025-11-01 19:59:45,073 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17860
2025-11-01 19:59:45,074 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_tenants
2025-11-01 19:59:45,074 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-11-01 19:59:45,088 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/vv82/00300f/r3tmp/kikimr_cluster_xalx64vw
2025-11-01 19:59:45,161 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd
2025-11-01 19:59:45,162 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18905
2025-11-01 19:59:45,162 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20768
2025-11-01 19:59:45,162 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63956
2025-11-01 19:59:45,163 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4619
2025-11-01 19:59:45,163 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 19:59:45,168 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00300f/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/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_rp50ns4q.log --grpc-port=18905 --mon-port=20768 --ic-port=17860
2025-11-01 19:59:45,168 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18905/1, disk_size 68719476736
2025-11-01 19:59:45,168 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 19:59:45,169 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00300f/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/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_rp50ns4q.log --grpc-port=18905 --mon-port=20768 --ic-port=17860
2025-11-01 19:59:45,170 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_rp50ns4q.log', '--grpc-port=18905', '--mon-port=20768', '--ic-port=17860')' in '/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-11-01 19:59:45,174 - DEBUG - ya.test - execute: Command pid: 3279810
2025-11-01 19:59:45,174 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18905/1
2025-11-01 19:59:45,182 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.7
2025-11-01 19:59:45,183 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.7
2025-11-01 19:59:45,183 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 19:59:45,188 - DEBUG - ya.test - execute: Command pid: 3279823
2025-11-01 19:59:45,991 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) rc: 0
2025-11-01 19:59:45,992 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) elapsed time (sec): 0.7992949485778809
2025-11-01 19:59:45,992 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) idrss: 0
2025-11-01 19:59:45,992 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) inblock: 0
2025-11-01 19:59:45,993 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) isrss: 0
2025-11-01 19:59:45,993 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) ixrss: 0
2025-11-01 19:59:45,993 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) majflt: 0
2025-11-01 19:59:45,993 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) maxrss: 538696
2025-11-01 19:59:45,993 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) minflt: 16133
2025-11-01 19:59:45,994 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) msgrcv: 0
2025-11-01 19:59:45,997 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) msgsnd: 0
2025-11-01 19:59:45,997 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) nivcsw: 135
2025-11-01 19:59:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) nsignals: 0
2025-11-01 19:59:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) nswap: 0
2025-11-01 19:59:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) nvcsw: 18
2025-11-01 19:59:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) oublock: 8
2025-11-01 19:59:45,998 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) stime: 0.328879
2025-11-01 19:59:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) utime: 0.144434
2025-11-01 19:59:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) wtime: 0.808
2025-11-01 19:59:46,004 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

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

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

2025-11-01 19:59:46,004 - DEBUG - ya.test - _save_outputs: Command (pid 3279823) errors:

2025-11-01 19:59:46,005 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 19:59:48,141 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd
2025-11-01 19:59:48,142 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18905', '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: 17860\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 19:59:48,153 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.8
2025-11-01 19:59:48,153 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.8
2025-11-01 19:59:48,154 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18905', '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: 17860\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 19:59:48,168 - DEBUG - ya.test - execute: Command pid: 3281601
2025-11-01 19:59:49,059 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) rc: 0
2025-11-01 19:59:49,060 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) elapsed time (sec): 0.8472816944122314
2025-11-01 19:59:49,060 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) idrss: 0
2025-11-01 19:59:49,060 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) inblock: 0
2025-11-01 19:59:49,060 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) isrss: 0
2025-11-01 19:59:49,060 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) ixrss: 0
2025-11-01 19:59:49,061 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) majflt: 0
2025-11-01 19:59:49,061 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) maxrss: 546956
2025-11-01 19:59:49,061 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) minflt: 18273
2025-11-01 19:59:49,061 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) msgrcv: 0
2025-11-01 19:59:49,061 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) msgsnd: 0
2025-11-01 19:59:49,061 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) nivcsw: 146
2025-11-01 19:59:49,062 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) nsignals: 0
2025-11-01 19:59:49,062 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) nswap: 0
2025-11-01 19:59:49,062 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) nvcsw: 123
2025-11-01 19:59:49,062 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) oublock: 8
2025-11-01 19:59:49,062 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) stime: 0.486257
2025-11-01 19:59:49,063 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) utime: 0.141457
2025-11-01 19:59:49,063 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) wtime: 0.861
2025-11-01 19:59:49,063 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-01 19:59:49,063 - DEBUG - ya.test - _save_outputs: Command (pid 3281601) errors:

2025-11-01 19:59:49,076 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd
2025-11-01 19:59:49,077 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18905', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-01 19:59:49,078 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.9
2025-11-01 19:59:49,078 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.9
2025-11-01 19:59:49,079 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18905', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 19:59:49,092 - DEBUG - ya.test - execute: Command pid: 3282665
2025-11-01 19:59:50,088 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) rc: 0
2025-11-01 19:59:50,088 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) elapsed time (sec): 0.9955065250396729
2025-11-01 19:59:50,088 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) idrss: 0
2025-11-01 19:59:50,098 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) inblock: 0
2025-11-01 19:59:50,098 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) isrss: 0
2025-11-01 19:59:50,098 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) ixrss: 0
2025-11-01 19:59:50,098 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) majflt: 0
2025-11-01 19:59:50,098 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) maxrss: 546864
2025-11-01 19:59:50,099 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) minflt: 18314
2025-11-01 19:59:50,099 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) msgrcv: 0
2025-11-01 19:59:50,099 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) msgsnd: 0
2025-11-01 19:59:50,099 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) nivcsw: 217
2025-11-01 19:59:50,099 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) nsignals: 0
2025-11-01 19:59:50,099 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) nswap: 0
2025-11-01 19:59:50,099 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) nvcsw: 109
2025-11-01 19:59:50,100 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) oublock: 8
2025-11-01 19:59:50,100 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) stime: 0.5527369999999999
2025-11-01 19:59:50,100 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) utime: 0.156668
2025-11-01 19:59:50,100 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) wtime: 1.009
2025-11-01 19:59:50,100 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 19:59:50,101 - DEBUG - ya.test - _save_outputs: Command (pid 3282665) errors:

2025-11-01 19:59:50,101 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 19:59:50,141 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 19:59:50,173 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_create_tables_enable_alter_database_create_hive_first--true_
2025-11-01 19:59:50,173 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-11-01 19:59:50,178 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_\' doesn\'t exist"
}

2025-11-01 19:59:50,178 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: wait console operation done
2025-11-01 19:59:50,685 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: console operation done
2025-11-01 19:59:50,686 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-11-01 19:59:50,695 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: tenant gone
2025-11-01 19:59:50,696 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: create path and declare internals
2025-11-01 19:59:51,229 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: start nodes and construct internals
2025-11-01 19:59:51,240 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd
2025-11-01 19:59:51,248 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14910
2025-11-01 19:59:51,250 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63059
2025-11-01 19:59:51,251 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21961
2025-11-01 19:59:51,252 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30420
2025-11-01 19:59:51,252 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27799
2025-11-01 19:59:51,253 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 19:59:51,253 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18905 --tenant=/Root/test_create_tables_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_aor2_z8s.log --grpc-port=14910 --mon-port=63059 --ic-port=21961
2025-11-01 19:59:51,267 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-11-01 19:59:51,267 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18905 --tenant=/Root/test_create_tables_enable_alter_database_create_hive_first--true_ --log-file-name=/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_aor2_z8s.log --grpc-port=14910 --mon-port=63059 --ic-port=21961
2025-11-01 19:59:51,269 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:18905', '--tenant=/Root/test_create_tables_enable_alter_database_create_hive_first--true_', '--log-file-name=/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_aor2_z8s.log', '--grpc-port=14910', '--mon-port=63059', '--ic-port=21961')' in '/home/runner/.ya/build/build_root/vv82/00300f/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk10/testing_out_stuff/test_tenants.py.TestTenants.test_create_tables.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-11-01 19:59:51,271 - DEBUG - ya.test - execute: Command pid: 3284349
2025-11-01 19:59:51,271 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14910/1
2025-11-01 19:59:51,284 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.10
2025-11-01 19:59:51,285 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.10
2025-11-01 19:59:51,285 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/vv82/00300f/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 19:59:51,304 - DEBUG - ya.test - execute: Command pid: 3284357
2025-11-01 19:59:52,512 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) rc: 0
2025-11-01 19:59:52,512 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) elapsed time (sec): 1.2042577266693115
2025-11-01 19:59:52,512 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) idrss: 0
2025-11-01 19:59:52,513 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) inblock: 0
2025-11-01 19:59:52,513 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) isrss: 0
2025-11-01 19:59:52,513 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) ixrss: 0
2025-11-01 19:59:52,513 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) majflt: 0
2025-11-01 19:59:52,513 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) maxrss: 538672
2025-11-01 19:59:52,513 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) minflt: 16207
2025-11-01 19:59:52,514 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) msgrcv: 0
2025-11-01 19:59:52,514 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) msgsnd: 0
2025-11-01 19:59:52,514 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) nivcsw: 191
2025-11-01 19:59:52,514 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) nsignals: 0
2025-11-01 19:59:52,514 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) nswap: 0
2025-11-01 19:59:52,514 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) nvcsw: 15
2025-11-01 19:59:52,515 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) oublock: 8
2025-11-01 19:59:52,515 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) stime: 0.6479039999999999
2025-11-01 19:59:52,515 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) utime: 0.133958
2025-11-01 19:59:52,515 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) wtime: 1.224
2025-11-01 19:59:52,516 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

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

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

2025-11-01 19:59:52,517 - DEBUG - ya.test - _save_outputs: Command (pid 3284357) errors:

2025-11-01 19:59:52,517 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 19:59:52,517 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: wait construction done
2025-11-01 19:59:58,541 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: create database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: database up
2025-11-01 19:59:58,566 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_tenants.py::TestTenants, test_name: test_create_tables[enable_alter_database_create_hive_first--true])
2025-11-01 19:59:58,567 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 19:59:58,569 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 19:59:58,570 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 19:59:58,587 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:58,588 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a56e50 state=pending>
2025-11-01 19:59:58,591 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-11-01 19:59:58,591 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:58,591 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:58,592 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-11-01 19:59:58,590 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/test_create_tables_enable_alter_database_create_hive_first--true_
2025-11-01 19:59:58,594 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 72487a06-d323-47f1-b652-d8b25cafb158, localhost:18905): creating call state
2025-11-01 19:59:58,594 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 72487a06-d323-47f1-b652-d8b25cafb158, localhost:18905): request = { database: "/Root/test_create_tables_enable_alter_database_create_hive_first--true_" }
2025-11-01 19:59:58,601 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 72487a06-d323-47f1-b652-d8b25cafb158, localhost:18905): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-mjpjznvske.auto.internal" port: 14910 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-11-01 19:59:58,613 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-mjpjznvske.auto.internal:14910, location , ssl: False>]>
2025-11-01 19:59:58,615 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 19:59:58,639 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18905
2025-11-01 19:59:58,640 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:58,645 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ea9c8fc2-94b2-4098-a850-2d53557fb378, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:58,645 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ea9c8fc2-94b2-4098-a850-2d53557fb378, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:58,652 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ea9c8fc2-94b2-4098-a850-2d53557fb378, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:58,652 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ea9c8fc2-94b2-4098-a850-2d53557fb378, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NWQ4YzYwZjgtN2U5ZTY2OWItZDhkOWI5NzItZWVhYjJmOTU=" } } } }
2025-11-01 19:59:58,653 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:58,653 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:58,653 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ea9c8fc2-94b2-4098-a850-2d53557fb378, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:58,654 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 8dab44cb-7a3f-45e3-8c68-4fe9dd9f238d, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:58,654 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 8dab44cb-7a3f-45e3-8c68-4fe9dd9f238d, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=NWQ4YzYwZjgtN2U5ZTY2OWItZDhkOWI5NzItZWVhYjJmOTU=" yql_text: "CREATE TABLE warmUp (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:58,743 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 8dab44cb-7a3f-45e3-8c68-4fe9dd9f238d, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:58,786 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:58,787 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:58,788 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a7b3a0 state=pending>
2025-11-01 19:59:58,793 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 1 < 100). will create a new session.
2025-11-01 19:59:58,793 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a79ff0>
2025-11-01 19:59:58,797 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a79ff0>
2025-11-01 19:59:58,798 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c3e85a21-6b6f-4035-b215-9cc086c0c438, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:58,798 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c3e85a21-6b6f-4035-b215-9cc086c0c438, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:58,801 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, c3e85a21-6b6f-4035-b215-9cc086c0c438, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:58,808 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c3e85a21-6b6f-4035-b215-9cc086c0c438, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ODU5OTljZjctZWNhOGFmZjMtMzkzYWY4MzYtNmVjODM3ZmI=" } } } }
2025-11-01 19:59:58,809 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a79ff0>
2025-11-01 19:59:58,809 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a79ff0>
2025-11-01 19:59:58,809 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, c3e85a21-6b6f-4035-b215-9cc086c0c438, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:58,810 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:58,811 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a54830 state=pending>
2025-11-01 19:59:58,811 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 2 < 100). will create a new session.
2025-11-01 19:59:58,811 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a55cd0>
2025-11-01 19:59:58,814 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a55cd0>
2025-11-01 19:59:58,816 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 10178171-ae20-42fe-8018-73b49c46f6cb, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:58,819 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 10178171-ae20-42fe-8018-73b49c46f6cb, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:58,827 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 10178171-ae20-42fe-8018-73b49c46f6cb, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:58,828 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 10178171-ae20-42fe-8018-73b49c46f6cb, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=OTI0ODY5OTUtNDlkOTQ3OWYtMjc2YjU5MzQtOTVmZDdlZTI=" } } } }
2025-11-01 19:59:58,833 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a55cd0>
2025-11-01 19:59:58,838 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a55cd0>
2025-11-01 19:59:58,841 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 10178171-ae20-42fe-8018-73b49c46f6cb, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:58,842 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:58,873 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a54ba0 state=pending>
2025-11-01 19:59:58,876 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 3 < 100). will create a new session.
2025-11-01 19:59:58,876 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a786f0>
2025-11-01 19:59:58,876 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a786f0>
2025-11-01 19:59:58,887 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0f490621-3d9c-45bd-b11c-ff61a94be53a, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:58,887 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0f490621-3d9c-45bd-b11c-ff61a94be53a, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:58,892 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0f490621-3d9c-45bd-b11c-ff61a94be53a, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:58,893 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0f490621-3d9c-45bd-b11c-ff61a94be53a, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YjkzNWFiNGEtYjljZTE3ZjAtNWQ1N2E0OWQtNTlhZTdjYw==" } } } }
2025-11-01 19:59:58,897 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a786f0>
2025-11-01 19:59:58,924 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a786f0>
2025-11-01 19:59:58,924 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 0f490621-3d9c-45bd-b11c-ff61a94be53a, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:58,925 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:58,925 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a7a7c0 state=pending>
2025-11-01 19:59:58,925 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 4 < 100). will create a new session.
2025-11-01 19:59:58,926 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a78ce0>
2025-11-01 19:59:58,978 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a78ce0>
2025-11-01 19:59:58,978 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ca432e78-bcb1-4e59-89db-980d71d54248, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:58,979 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ca432e78-bcb1-4e59-89db-980d71d54248, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:58,986 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ca432e78-bcb1-4e59-89db-980d71d54248, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:58,988 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ca432e78-bcb1-4e59-89db-980d71d54248, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=OTllMDEzYmQtNjE2MDJlNy1hOWM2MTI5NC0xZmE1NDIy" } } } }
2025-11-01 19:59:58,989 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ca432e78-bcb1-4e59-89db-980d71d54248, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:58,989 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a78ce0>
2025-11-01 19:59:58,989 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a78ce0>
2025-11-01 19:59:58,990 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:58,990 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a7e720 state=pending>
2025-11-01 19:59:58,997 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 5 < 100). will create a new session.
2025-11-01 19:59:58,999 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a7e270>
2025-11-01 19:59:58,999 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a7e270>
2025-11-01 19:59:59,000 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7b9e658b-3558-4448-8992-96d10401ef9b, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,000 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7b9e658b-3558-4448-8992-96d10401ef9b, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,005 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7b9e658b-3558-4448-8992-96d10401ef9b, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,009 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7b9e658b-3558-4448-8992-96d10401ef9b, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ODAxNmY0NDYtYWZlMzY5NTktN2IzOGY1NmUtNzQ3NDIxMTg=" } } } }
2025-11-01 19:59:59,010 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a7e270>
2025-11-01 19:59:59,010 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a7e270>
2025-11-01 19:59:59,012 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 7b9e658b-3558-4448-8992-96d10401ef9b, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,014 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:59,015 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a7fda0 state=pending>
2025-11-01 19:59:59,015 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 6 < 100). will create a new session.
2025-11-01 19:59:59,021 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a7ec20>
2025-11-01 19:59:59,029 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a7ec20>
2025-11-01 19:59:59,030 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 237da11c-ad6f-4a29-afd5-b3be1d4ec0ab, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,039 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 237da11c-ad6f-4a29-afd5-b3be1d4ec0ab, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,053 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 237da11c-ad6f-4a29-afd5-b3be1d4ec0ab, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,054 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 237da11c-ad6f-4a29-afd5-b3be1d4ec0ab, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=NGNiYjJlMjItNDI5N2Q0MDUtYTVhOTQ0OGUtNmY0NTA5MWQ=" } } } }
2025-11-01 19:59:59,068 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a7ec20>
2025-11-01 19:59:59,068 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a7ec20>
2025-11-01 19:59:59,069 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 237da11c-ad6f-4a29-afd5-b3be1d4ec0ab, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,069 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:59,073 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a7d190 state=pending>
2025-11-01 19:59:59,073 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 7 < 100). will create a new session.
2025-11-01 19:59:59,076 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a7c0b0>
2025-11-01 19:59:59,076 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a7c0b0>
2025-11-01 19:59:59,077 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 1e4e17b0-3c50-4d1e-8fb9-75798dc66e77, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,078 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 1e4e17b0-3c50-4d1e-8fb9-75798dc66e77, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,081 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 1e4e17b0-3c50-4d1e-8fb9-75798dc66e77, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,093 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 1e4e17b0-3c50-4d1e-8fb9-75798dc66e77, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ODBjZDg0MC1jNWI2MWNmYy05NTVmOWU3NS02ZDBiMjliNA==" } } } }
2025-11-01 19:59:59,094 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a7c0b0>
2025-11-01 19:59:59,094 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a7c0b0>
2025-11-01 19:59:59,095 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 1e4e17b0-3c50-4d1e-8fb9-75798dc66e77, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,096 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:59,136 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a3abd0 state=pending>
2025-11-01 19:59:59,140 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 8 < 100). will create a new session.
2025-11-01 19:59:59,140 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a77120>
2025-11-01 19:59:59,141 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a77120>
2025-11-01 19:59:59,169 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5faf7dc9-15ae-4b5f-887b-c64bb3614242, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,175 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5faf7dc9-15ae-4b5f-887b-c64bb3614242, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,181 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5faf7dc9-15ae-4b5f-887b-c64bb3614242, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,182 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5faf7dc9-15ae-4b5f-887b-c64bb3614242, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=MTIzMWQxZC0zNTljYmRmZC1hYjA0MjljMy00ODllOGYzZQ==" } } } }
2025-11-01 19:59:59,182 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a77120>
2025-11-01 19:59:59,182 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a77120>
2025-11-01 19:59:59,183 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5faf7dc9-15ae-4b5f-887b-c64bb3614242, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,183 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-01 19:59:59,186 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7faab6a758c0 state=pending>
2025-11-01 19:59:59,193 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 9 < 100). will create a new session.
2025-11-01 19:59:59,193 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7faab6a76720>
2025-11-01 19:59:59,194 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7faab6a76720>
2025-11-01 19:59:59,195 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5c7465ae-23c8-4ea5-90fa-cfda8854441e, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,208 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5c7465ae-23c8-4ea5-90fa-cfda8854441e, ghrun-mjpjznvske.auto.internal:14910): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,213 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5c7465ae-23c8-4ea5-90fa-cfda8854441e, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,215 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5c7465ae-23c8-4ea5-90fa-cfda8854441e, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ODVhZDI1NjQtZmU3ZTc3ZjQtMTcyN2FhY2YtMWU3OGIxNTQ=" } } } }
2025-11-01 19:59:59,215 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a76720>
2025-11-01 19:59:59,216 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7faab6a76720>
2025-11-01 19:59:59,216 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5c7465ae-23c8-4ea5-90fa-cfda8854441e, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,216 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 679e0b0e-e125-45aa-bafc-9446f8a63129, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,223 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 679e0b0e-e125-45aa-bafc-9446f8a63129, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=NWQ4YzYwZjgtN2U5ZTY2OWItZDhkOWI5NzItZWVhYjJmOTU=" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, d4f1223b-b1fc-4359-8676-7f45f790fac1, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,229 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, d4f1223b-b1fc-4359-8676-7f45f790fac1, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODU5OTljZjctZWNhOGFmZjMtMzkzYWY4MzYtNmVjODM3ZmI=" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,261 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 036d2594-b5b7-40e7-8b46-033040b2d1eb, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,289 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 036d2594-b5b7-40e7-8b46-033040b2d1eb, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=OTI0ODY5OTUtNDlkOTQ3OWYtMjc2YjU5MzQtOTVmZDdlZTI=" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 7d3d65eb-be2a-42e8-a9e0-d5e4d0de4e55, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 7d3d65eb-be2a-42e8-a9e0-d5e4d0de4e55, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=YjkzNWFiNGEtYjljZTE3ZjAtNWQ1N2E0OWQtNTlhZTdjYw==" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,291 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, d4f1223b-b1fc-4359-8676-7f45f790fac1, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,365 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, d4f1223b-b1fc-4359-8676-7f45f790fac1, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: OVERLOADED issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exists but creating right now (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateCreate), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" issue_code: 2010 severity: 1 } } } } }
2025-11-01 19:59:59,367 - INFO - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, d4f1223b-b1fc-4359-8676-7f45f790fac1, ghrun-mjpjznvske.auto.internal:14910): received exception, message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exists but creating right now (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateCreate), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" issue_code: 2010 severity: 1 } } (server_code: 400060)
2025-11-01 19:59:59,320 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, ec246b5a-cd71-414a-84e6-0f2adc64094f, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,387 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 679e0b0e-e125-45aa-bafc-9446f8a63129, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,388 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, ec246b5a-cd71-414a-84e6-0f2adc64094f, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=OTllMDEzYmQtNjE2MDJlNy1hOWM2MTI5NC0xZmE1NDIy" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,393 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 679e0b0e-e125-45aa-bafc-9446f8a63129, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,395 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 679e0b0e-e125-45aa-bafc-9446f8a63129, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,396 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, ff831c37-4cad-43de-9b11-bcc49b206b94, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,397 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, ff831c37-4cad-43de-9b11-bcc49b206b94, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODAxNmY0NDYtYWZlMzY5NTktN2IzOGY1NmUtNzQ3NDIxMTg=" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,414 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, c43df2e0-4d87-4047-8d6a-0e197e8b2e8e, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,436 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, c43df2e0-4d87-4047-8d6a-0e197e8b2e8e, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=NGNiYjJlMjItNDI5N2Q0MDUtYTVhOTQ0OGUtNmY0NTA5MWQ=" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,437 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, ccb410fe-3d69-47ce-9c31-d31fbc2037be, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,398 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 036d2594-b5b7-40e7-8b46-033040b2d1eb, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,458 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, ccb410fe-3d69-47ce-9c31-d31fbc2037be, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODBjZDg0MC1jNWI2MWNmYy05NTVmOWU3NS02ZDBiMjliNA==" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,461 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 036d2594-b5b7-40e7-8b46-033040b2d1eb, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,465 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 036d2594-b5b7-40e7-8b46-033040b2d1eb, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 6f7164dd-348b-4f1d-a3d3-5104260ad070, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 6f7164dd-348b-4f1d-a3d3-5104260ad070, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=MTIzMWQxZC0zNTljYmRmZC1hYjA0MjljMy00ODllOGYzZQ==" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,481 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 7d3d65eb-be2a-42e8-a9e0-d5e4d0de4e55, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteSchemeQuery, 0f2f5621-6999-4d2e-8754-c3598341dc8e, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,491 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 7d3d65eb-be2a-42e8-a9e0-d5e4d0de4e55, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,492 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteSchemeQuery, 0f2f5621-6999-4d2e-8754-c3598341dc8e, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODVhZDI1NjQtZmU3ZTc3ZjQtMTcyN2FhY2YtMWU3OGIxNTQ=" yql_text: "CREATE TABLE `/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations` (id utf8, PRIMARY KEY (id));" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,493 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 7d3d65eb-be2a-42e8-a9e0-d5e4d0de4e55, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,495 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, ec246b5a-cd71-414a-84e6-0f2adc64094f, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,497 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, ec246b5a-cd71-414a-84e6-0f2adc64094f, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,498 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, ec246b5a-cd71-414a-84e6-0f2adc64094f, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,498 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, ff831c37-4cad-43de-9b11-bcc49b206b94, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,499 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, ff831c37-4cad-43de-9b11-bcc49b206b94, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,499 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, ff831c37-4cad-43de-9b11-bcc49b206b94, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,500 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, c43df2e0-4d87-4047-8d6a-0e197e8b2e8e, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,500 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, c43df2e0-4d87-4047-8d6a-0e197e8b2e8e, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,501 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, c43df2e0-4d87-4047-8d6a-0e197e8b2e8e, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,501 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, ccb410fe-3d69-47ce-9c31-d31fbc2037be, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,502 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, ccb410fe-3d69-47ce-9c31-d31fbc2037be, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,503 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, ccb410fe-3d69-47ce-9c31-d31fbc2037be, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,503 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 6f7164dd-348b-4f1d-a3d3-5104260ad070, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,517 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 6f7164dd-348b-4f1d-a3d3-5104260ad070, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,517 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 6f7164dd-348b-4f1d-a3d3-5104260ad070, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,520 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 0f2f5621-6999-4d2e-8754-c3598341dc8e, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,521 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteSchemeQuery, 0f2f5621-6999-4d2e-8754-c3598341dc8e, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS issues { message: "Execution" issue_code: 1060 severity: 1 issues { position { row: 1 column: 156 } message: "Executing CREATE TABLE" end_position { row: 1 column: 156 } severity: 1 issues { message: "Success" issue_code: 4 severity: 1 issues { message: "Check failed: path: \'/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations\', error: path exist, request accepts it (id: [OwnerId: 72075186224037897, LocalPathId: 11], type: EPathTypeTable, state: EPathStateNoChanges), source_location: ydb/core/tx/schemeshard/schemeshard__operation_create_table.cpp:504" severity: 1 } } } } } }
2025-11-01 19:59:59,522 - DEBUG - ydb.connection - _on_response_callback: RpcState(ExecuteSchemeQuery, 0f2f5621-6999-4d2e-8754-c3598341dc8e, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,522 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:59,526 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a79ff0>
2025-11-01 19:59:59,532 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a55cd0>
2025-11-01 19:59:59,532 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a786f0>
2025-11-01 19:59:59,533 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a78ce0>
2025-11-01 19:59:59,533 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a7e270>
2025-11-01 19:59:59,533 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a7ec20>
2025-11-01 19:59:59,533 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a7c0b0>
2025-11-01 19:59:59,534 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a77120>
2025-11-01 19:59:59,534 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a76720>
2025-11-01 19:59:59,534 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribeTable, 864c6d37-1619-426d-9251-6cffa61a06e3, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,535 - DEBUG - ydb.connection - _log_request: RpcState(DescribeTable, 864c6d37-1619-426d-9251-6cffa61a06e3, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=NWQ4YzYwZjgtN2U5ZTY2OWItZDhkOWI5NzItZWVhYjJmOTU=" path: "/Root/test_create_tables_enable_alter_database_create_hive_first--true_/temp/hardware/default/compute_az/allocations" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 19:59:59,541 - DEBUG - ydb.connection - _log_response: RpcState(DescribeTable, 864c6d37-1619-426d-9251-6cffa61a06e3, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "allocations" owner: "root@builtin" type: TABLE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } created_at { plan_step: 1762027199340 tx_id: 281474976720660 } } columns { name: "id" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" storage_settings { store_external_blobs: DISABLED } column_families { name: "default" compression: COMPRESSION_NONE } partitioning_settings { partitioning_by_size: ENABLED partition_size_mb: 2048 partitioning_by_load: DISABLED min_partitions_count: 1 } } } } }
2025-11-01 19:59:59,545 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:59,546 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 19:59:59,546 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-11-01 19:59:59,547 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-01 19:59:59,547 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-11-01 19:59:59,547 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a79ff0>, reason: session-pool-terminated
2025-11-01 19:59:59,561 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a79ff0> is no longer active. Current active count 9.
2025-11-01 19:59:59,562 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 8e77513a-9319-453c-80e4-0e2c91e38df5, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,584 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 8e77513a-9319-453c-80e4-0e2c91e38df5, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODU5OTljZjctZWNhOGFmZjMtMzkzYWY4MzYtNmVjODM3ZmI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,549 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-11-01 19:59:59,596 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8e77513a-9319-453c-80e4-0e2c91e38df5, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,597 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 8e77513a-9319-453c-80e4-0e2c91e38df5, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,597 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 8e77513a-9319-453c-80e4-0e2c91e38df5, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,597 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a79ff0>
2025-11-01 19:59:59,597 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a55cd0>, reason: session-pool-terminated
2025-11-01 19:59:59,598 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a55cd0> is no longer active. Current active count 8.
2025-11-01 19:59:59,598 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 3a9e3a20-9c9a-4b18-a663-ca948ed9f49f, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,598 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 3a9e3a20-9c9a-4b18-a663-ca948ed9f49f, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=OTI0ODY5OTUtNDlkOTQ3OWYtMjc2YjU5MzQtOTVmZDdlZTI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,599 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a55cd0>
2025-11-01 19:59:59,616 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a786f0>, reason: session-pool-terminated
2025-11-01 19:59:59,616 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a786f0> is no longer active. Current active count 7.
2025-11-01 19:59:59,616 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 058fcf31-a638-402e-8ad3-1239036287f8, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,624 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 058fcf31-a638-402e-8ad3-1239036287f8, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=YjkzNWFiNGEtYjljZTE3ZjAtNWQ1N2E0OWQtNTlhZTdjYw==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,604 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3a9e3a20-9c9a-4b18-a663-ca948ed9f49f, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,660 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 3a9e3a20-9c9a-4b18-a663-ca948ed9f49f, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,660 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 3a9e3a20-9c9a-4b18-a663-ca948ed9f49f, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,661 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 058fcf31-a638-402e-8ad3-1239036287f8, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,661 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 058fcf31-a638-402e-8ad3-1239036287f8, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,661 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 058fcf31-a638-402e-8ad3-1239036287f8, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,652 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a786f0>
2025-11-01 19:59:59,662 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a78ce0>, reason: session-pool-terminated
2025-11-01 19:59:59,662 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a78ce0> is no longer active. Current active count 6.
2025-11-01 19:59:59,663 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, cb7ceee6-b078-42e4-8142-ce8ecdae47bd, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,664 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, cb7ceee6-b078-42e4-8142-ce8ecdae47bd, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=OTllMDEzYmQtNjE2MDJlNy1hOWM2MTI5NC0xZmE1NDIy" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,700 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cb7ceee6-b078-42e4-8142-ce8ecdae47bd, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,701 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, cb7ceee6-b078-42e4-8142-ce8ecdae47bd, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,701 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cb7ceee6-b078-42e4-8142-ce8ecdae47bd, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,701 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a78ce0>
2025-11-01 19:59:59,702 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a7e270>, reason: session-pool-terminated
2025-11-01 19:59:59,702 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a7e270> is no longer active. Current active count 5.
2025-11-01 19:59:59,702 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 981fc9de-f39a-44cb-ac19-ba1ef82a9357, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,703 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 981fc9de-f39a-44cb-ac19-ba1ef82a9357, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODAxNmY0NDYtYWZlMzY5NTktN2IzOGY1NmUtNzQ3NDIxMTg=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,703 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a7e270>
2025-11-01 19:59:59,704 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a7ec20>, reason: session-pool-terminated
2025-11-01 19:59:59,716 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a7ec20> is no longer active. Current active count 4.
2025-11-01 19:59:59,728 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a93e4468-d414-492b-ad53-45285fcca3ee, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,728 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a93e4468-d414-492b-ad53-45285fcca3ee, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=NGNiYjJlMjItNDI5N2Q0MDUtYTVhOTQ0OGUtNmY0NTA5MWQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,708 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 981fc9de-f39a-44cb-ac19-ba1ef82a9357, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,729 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 981fc9de-f39a-44cb-ac19-ba1ef82a9357, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,730 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 981fc9de-f39a-44cb-ac19-ba1ef82a9357, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,736 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a7ec20>
2025-11-01 19:59:59,744 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a7c0b0>, reason: session-pool-terminated
2025-11-01 19:59:59,744 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a7c0b0> is no longer active. Current active count 3.
2025-11-01 19:59:59,745 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 54b106b9-f6f8-4fec-abeb-7f8e42a88884, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,745 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 54b106b9-f6f8-4fec-abeb-7f8e42a88884, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODBjZDg0MC1jNWI2MWNmYy05NTVmOWU3NS02ZDBiMjliNA==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,746 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a7c0b0>
2025-11-01 19:59:59,746 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a77120>, reason: session-pool-terminated
2025-11-01 19:59:59,750 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a77120> is no longer active. Current active count 2.
2025-11-01 19:59:59,751 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ca0c0302-628d-4be0-8bab-df84ec5aaa00, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,753 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ca0c0302-628d-4be0-8bab-df84ec5aaa00, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=MTIzMWQxZC0zNTljYmRmZC1hYjA0MjljMy00ODllOGYzZQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,753 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a77120>
2025-11-01 19:59:59,754 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a76720>, reason: session-pool-terminated
2025-11-01 19:59:59,754 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a76720> is no longer active. Current active count 1.
2025-11-01 19:59:59,754 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b9f203ca-c907-45fe-9694-8d186d0d914d, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,754 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b9f203ca-c907-45fe-9694-8d186d0d914d, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=ODVhZDI1NjQtZmU3ZTc3ZjQtMTcyN2FhY2YtMWU3OGIxNTQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,755 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a76720>
2025-11-01 19:59:59,755 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7faab6a57850>, reason: session-pool-terminated
2025-11-01 19:59:59,755 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7faab6a57850> is no longer active. Current active count 0.
2025-11-01 19:59:59,765 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 5d1b70e0-8cdc-4b2d-af61-4e02f61b85e6, ghrun-mjpjznvske.auto.internal:14910): creating call state
2025-11-01 19:59:59,765 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 5d1b70e0-8cdc-4b2d-af61-4e02f61b85e6, ghrun-mjpjznvske.auto.internal:14910): request = { session_id: "ydb://session/3?node_id=50000&id=NWQ4YzYwZjgtN2U5ZTY2OWItZDhkOWI5NzItZWVhYjJmOTU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-01 19:59:59,736 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, a93e4468-d414-492b-ad53-45285fcca3ee, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,766 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7faab6a57850>
2025-11-01 19:59:59,775 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-11-01 19:59:59,775 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 19:59:59,775 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 19:59:59,771 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a93e4468-d414-492b-ad53-45285fcca3ee, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,777 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, a93e4468-d414-492b-ad53-45285fcca3ee, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,778 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 54b106b9-f6f8-4fec-abeb-7f8e42a88884, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,782 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 54b106b9-f6f8-4fec-abeb-7f8e42a88884, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,782 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 54b106b9-f6f8-4fec-abeb-7f8e42a88884, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,783 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ca0c0302-628d-4be0-8bab-df84ec5aaa00, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,783 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ca0c0302-628d-4be0-8bab-df84ec5aaa00, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,783 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, ca0c0302-628d-4be0-8bab-df84ec5aaa00, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,776 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-mjpjznvske.auto.internal:14910
2025-11-01 19:59:59,787 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 19:59:59,784 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, b9f203ca-c907-45fe-9694-8d186d0d914d, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,788 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b9f203ca-c907-45fe-9694-8d186d0d914d, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,788 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, b9f203ca-c907-45fe-9694-8d186d0d914d, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,789 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 5d1b70e0-8cdc-4b2d-af61-4e02f61b85e6, ghrun-mjpjznvske.auto.internal:14910): on response callback started
2025-11-01 19:59:59,789 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 5d1b70e0-8cdc-4b2d-af61-4e02f61b85e6, ghrun-mjpjznvske.auto.internal:14910): response = { operation { ready: true status: SUCCESS } }
2025-11-01 19:59:59,789 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 5d1b70e0-8cdc-4b2d-af61-4e02f61b85e6, ghrun-mjpjznvske.auto.internal:14910): on response callback success
2025-11-01 19:59:59,775 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18905
2025-11-01 19:59:59,793 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: remove path and dismantle internals
2025-11-01 19:59:59,794 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/test_create_tables_enable_alter_database_create_hive_first--true_
2025-11-01 19:59:59,794 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: send console operation, token None
2025-11-01 19:59:59,805 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/test_create_tables_enable_alter_database_create_hive_first--true_&cmstid=72057594037936131&txid=1762027199797969&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-11-01 19:59:59,827 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: wait console operation done
2025-11-01 20:00:00,331 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: console operation done
2025-11-01 20:00:00,338 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: wait tenant gone
2025-11-01 20:00:00,342 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/test_create_tables_enable_alter_database_create_hive_first--true_: tenant gone
2025-11-01 20:00:00,352 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: stop nodes
2025-11-01 20:00:03,355 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:14910/1
2025-11-01 20:00:03,367 - DEBUG - ydb.tests.library.fixtures - ydb_database_ctx: destroy database /Root/test_create_tables_enable_alter_database_create_hive_first--true_: database down
