2025-12-04 21:00:50,017 - INFO - ya.test.2486776  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:00:50,018 - INFO - ya.test.2486776  - pytest_runtest_setup: test_when_deactivate_fat_tenant_creation_another_tenant_is_ok[enable_alter_database_create_hive_first--true]
2025-12-04 21:00:50,018 - INFO - ya.test.2486776  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:00:50,018 - INFO - ya.test.2486776  - pytest_runtest_setup: Test setup
2025-12-04 21:00:50,086 - DEBUG - ya.test.2486776  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd
2025-12-04 21:00:50,089 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27072
2025-12-04 21:00:50,091 - INFO - ydb.tests.library.fixtures.2486776  - ydb_cluster: setup ydb_cluster for __tests__.test_tenants
2025-12-04 21:00:50,091 - INFO - ydb.tests.library.fixtures.2486776  - ydb_cluster: setup ydb_cluster as local
2025-12-04 21:00:50,092 - DEBUG - ydb.tests.library.harness.kikimr_runner.2486776  - start: Working directory: /home/runner/.ya/build/build_root/6mdl/004fcb/r3tmp/kikimr_cluster_3l7l8142
2025-12-04 21:00:50,148 - DEBUG - ya.test.2486776  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd
2025-12-04 21:00:50,148 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63373
2025-12-04 21:00:50,153 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62040
2025-12-04 21:00:50,154 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26604
2025-12-04 21:00:50,154 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26605
2025-12-04 21:00:50,154 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17479
2025-12-04 21:00:50,155 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:00:50,155 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/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/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_u4edr0xf.log --grpc-port=63373 --mon-port=62040 --ic-port=27072
2025-12-04 21:00:50,155 - DEBUG - ydb.tests.library.harness.kikimr_runner.2486776  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:63373/1, disk_size 68719476736
2025-12-04 21:00:50,156 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:00:50,156 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/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/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_u4edr0xf.log --grpc-port=63373 --mon-port=62040 --ic-port=27072
2025-12-04 21:00:50,157 - DEBUG - ya.test.2486776  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_u4edr0xf.log', '--grpc-port=63373', '--mon-port=62040', '--ic-port=27072')' in '/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-12-04 21:00:50,171 - DEBUG - ya.test.2486776  - execute: Command pid: 2488205
2025-12-04 21:00:50,178 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Started node localhost:63373/1
2025-12-04 21:00:50,180 - DEBUG - ya.test.2486776  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-04 21:00:50,190 - DEBUG - ya.test.2486776  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-04 21:00:50,197 - DEBUG - ya.test.2486776  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:00:50,229 - DEBUG - ya.test.2486776  - execute: Command pid: 2488211
2025-12-04 21:00:51,847 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) rc: 0
2025-12-04 21:00:51,847 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) elapsed time (sec): 1.6045312881469727
2025-12-04 21:00:51,847 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) idrss: 0
2025-12-04 21:00:51,848 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) inblock: 0
2025-12-04 21:00:51,848 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) isrss: 0
2025-12-04 21:00:51,848 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) ixrss: 0
2025-12-04 21:00:51,848 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) majflt: 0
2025-12-04 21:00:51,848 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) maxrss: 546888
2025-12-04 21:00:51,848 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) minflt: 17121
2025-12-04 21:00:51,849 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) msgrcv: 0
2025-12-04 21:00:51,849 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) msgsnd: 0
2025-12-04 21:00:51,850 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) nivcsw: 42
2025-12-04 21:00:51,850 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) nsignals: 0
2025-12-04 21:00:51,850 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) nswap: 0
2025-12-04 21:00:51,850 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) nvcsw: 409
2025-12-04 21:00:51,851 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) oublock: 8
2025-12-04 21:00:51,851 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) stime: 1.358368
2025-12-04 21:00:51,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) utime: 0.11057199999999999
2025-12-04 21:00:51,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) wtime: 1.619
2025-12-04 21:00:51,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:00:51,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2488211) errors:

2025-12-04 21:00:51,853 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Node 1 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:00:53,904 - DEBUG - ya.test.2486776  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd
2025-12-04 21:00:53,904 - DEBUG - ydb.tests.library.harness.kikimr_runner.2486776  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63373', '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: 27072\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-04 21:00:53,905 - DEBUG - ya.test.2486776  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-04 21:00:53,906 - DEBUG - ya.test.2486776  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-04 21:00:53,906 - DEBUG - ya.test.2486776  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63373', '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: 27072\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 21:00:53,907 - DEBUG - ya.test.2486776  - execute: Command pid: 2490345
2025-12-04 21:00:54,636 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) rc: 0
2025-12-04 21:00:54,636 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) elapsed time (sec): 0.7280187606811523
2025-12-04 21:00:54,636 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) idrss: 0
2025-12-04 21:00:54,636 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) inblock: 0
2025-12-04 21:00:54,637 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) isrss: 0
2025-12-04 21:00:54,637 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) ixrss: 0
2025-12-04 21:00:54,637 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) majflt: 0
2025-12-04 21:00:54,637 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) maxrss: 555396
2025-12-04 21:00:54,638 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) minflt: 18402
2025-12-04 21:00:54,639 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) msgrcv: 0
2025-12-04 21:00:54,639 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) msgsnd: 0
2025-12-04 21:00:54,639 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) nivcsw: 80
2025-12-04 21:00:54,640 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) nsignals: 0
2025-12-04 21:00:54,640 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) nswap: 0
2025-12-04 21:00:54,640 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) nvcsw: 160
2025-12-04 21:00:54,640 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) oublock: 8
2025-12-04 21:00:54,640 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) stime: 0.566643
2025-12-04 21:00:54,641 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) utime: 0.116545
2025-12-04 21:00:54,641 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) wtime: 0.729
2025-12-04 21:00:54,641 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-04 21:00:54,641 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2490345) errors:

2025-12-04 21:00:54,642 - DEBUG - ya.test.2486776  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd
2025-12-04 21:00:54,643 - DEBUG - ydb.tests.library.harness.kikimr_runner.2486776  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63373', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-04 21:00:54,644 - DEBUG - ya.test.2486776  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-04 21:00:54,644 - DEBUG - ya.test.2486776  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-04 21:00:54,644 - DEBUG - ya.test.2486776  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63373', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 21:00:54,645 - DEBUG - ya.test.2486776  - execute: Command pid: 2491269
2025-12-04 21:00:55,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) rc: 0
2025-12-04 21:00:55,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) elapsed time (sec): 0.765148401260376
2025-12-04 21:00:55,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) idrss: 0
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) inblock: 0
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) isrss: 0
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) ixrss: 0
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) majflt: 0
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) maxrss: 555376
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) minflt: 18404
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) msgrcv: 0
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) msgsnd: 0
2025-12-04 21:00:55,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) nivcsw: 16
2025-12-04 21:00:55,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) nsignals: 0
2025-12-04 21:00:55,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) nswap: 0
2025-12-04 21:00:55,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) nvcsw: 141
2025-12-04 21:00:55,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) oublock: 8
2025-12-04 21:00:55,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) stime: 0.5933419999999999
2025-12-04 21:00:55,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) utime: 0.12426899999999999
2025-12-04 21:00:55,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) wtime: 0.766
2025-12-04 21:00:55,414 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-04 21:00:55,414 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2491269) errors:

2025-12-04 21:00:55,414 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-04 21:00:55,426 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __run: Cluster started and initialized
2025-12-04 21:00:55,435 - INFO - ya.test.2486776  - pytest_runtest_call: Test call (class_name: test_tenants.py::TestTenants, test_name: test_when_deactivate_fat_tenant_creation_another_tenant_is_ok[enable_alter_database_create_hive_first--true])
2025-12-04 21:00:55,435 - INFO - __tests__.test_tenants.2486776  - test_when_deactivate_fat_tenant_creation_another_tenant_is_ok: create fat tenant
2025-12-04 21:00:55,439 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - remove_database: /Root/database_0
2025-12-04 21:00:55,440 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_0: send console operation, token None
2025-12-04 21:00:55,443 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_0: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/database_0\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/database_0\' doesn\'t exist"
}

2025-12-04 21:00:55,443 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_0: wait console operation done
2025-12-04 21:00:55,947 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_0: console operation done
2025-12-04 21:00:55,948 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_0: wait tenant gone
2025-12-04 21:00:55,950 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_0: tenant gone
2025-12-04 21:00:55,950 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_0: create path and declare internals
2025-12-04 21:00:56,458 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_0: start nodes and construct internals
2025-12-04 21:00:56,459 - DEBUG - ya.test.2486776  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd
2025-12-04 21:00:56,459 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9740
2025-12-04 21:00:56,460 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14637
2025-12-04 21:00:56,460 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14755
2025-12-04 21:00:56,460 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25523
2025-12-04 21:00:56,461 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28911
2025-12-04 21:00:56,461 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:00:56,461 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:63373 --tenant=/Root/database_0 --log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile__1m0s40n.log --grpc-port=9740 --mon-port=14637 --ic-port=14755
2025-12-04 21:00:56,462 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:00:56,462 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:63373 --tenant=/Root/database_0 --log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile__1m0s40n.log --grpc-port=9740 --mon-port=14637 --ic-port=14755
2025-12-04 21:00:56,463 - DEBUG - ya.test.2486776  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:63373', '--tenant=/Root/database_0', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile__1m0s40n.log', '--grpc-port=9740', '--mon-port=14637', '--ic-port=14755')' in '/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-12-04 21:00:56,464 - DEBUG - ya.test.2486776  - execute: Command pid: 2492497
2025-12-04 21:00:56,465 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Started node localhost:9740/1
2025-12-04 21:00:56,466 - DEBUG - ya.test.2486776  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-04 21:00:56,467 - DEBUG - ya.test.2486776  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-04 21:00:56,467 - DEBUG - ya.test.2486776  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:00:56,468 - DEBUG - ya.test.2486776  - execute: Command pid: 2492498
2025-12-04 21:00:56,850 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) rc: 0
2025-12-04 21:00:56,850 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) elapsed time (sec): 0.38132333755493164
2025-12-04 21:00:56,851 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) idrss: 0
2025-12-04 21:00:56,851 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) inblock: 0
2025-12-04 21:00:56,851 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) isrss: 0
2025-12-04 21:00:56,851 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) ixrss: 0
2025-12-04 21:00:56,851 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) majflt: 1
2025-12-04 21:00:56,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) maxrss: 546852
2025-12-04 21:00:56,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) minflt: 17698
2025-12-04 21:00:56,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) msgrcv: 0
2025-12-04 21:00:56,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) msgsnd: 0
2025-12-04 21:00:56,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) nivcsw: 1
2025-12-04 21:00:56,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) nsignals: 0
2025-12-04 21:00:56,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) nswap: 0
2025-12-04 21:00:56,853 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) nvcsw: 14
2025-12-04 21:00:56,853 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) oublock: 8
2025-12-04 21:00:56,853 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) stime: 0.214138
2025-12-04 21:00:56,853 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) utime: 0.118973
2025-12-04 21:00:56,853 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) wtime: 0.382
2025-12-04 21:00:56,853 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:00:56,854 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2492498) errors:

2025-12-04 21:00:56,854 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Node 1 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:00:56,854 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_0: wait construction done
2025-12-04 21:00:58,861 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_0: database up
2025-12-04 21:00:58,862 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:00:58,865 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:00:58,868 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:00:58,868 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 21:00:58,875 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Subscribe waiter <Future at 0x7ffae8dac060 state=pending>
2025-12-04 21:00:58,877 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 21:00:58,878 - DEBUG - ydb.table.SessionPool.2486776  - _create: Created session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:00:58,879 - DEBUG - ydb.table.SessionPool.2486776  - _prepare: Preparing session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:00:58,879 - ERROR - ydb.table.SessionPool.2486776  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 21:00:58,885 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Resolving endpoints for database /Root/database_0
2025-12-04 21:00:58,886 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(ListEndpoints, ef847f8e-b53b-4952-8865-16a5236dc395, localhost:63373): creating call state
2025-12-04 21:00:58,887 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(ListEndpoints, ef847f8e-b53b-4952-8865-16a5236dc395, localhost:63373): request = { database: "/Root/database_0" }
2025-12-04 21:00:58,894 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(ListEndpoints, ef847f8e-b53b-4952-8865-16a5236dc395, localhost:63373): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-if77zlumgq.auto.internal" port: 9740 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-12-04 21:00:58,897 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - _add_debug_details: Resolved endpoints for database /Root/database_0: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-if77zlumgq.auto.internal:9740, location , ssl: False>]>
2025-12-04 21:00:58,897 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:00:58,910 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:00:58,910 - DEBUG - ydb.table.SessionPool.2486776  - _prepare: Preparing session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:00:58,911 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(CreateSession, a801d2c2-0822-47a7-bc23-d637e1b9d4a2, ghrun-if77zlumgq.auto.internal:9740): creating call state
2025-12-04 21:00:58,912 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(CreateSession, a801d2c2-0822-47a7-bc23-d637e1b9d4a2, ghrun-if77zlumgq.auto.internal:9740): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:00:58,917 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(CreateSession, a801d2c2-0822-47a7-bc23-d637e1b9d4a2, ghrun-if77zlumgq.auto.internal:9740): on response callback started
2025-12-04 21:00:58,918 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(CreateSession, a801d2c2-0822-47a7-bc23-d637e1b9d4a2, ghrun-if77zlumgq.auto.internal:9740): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YzQ1ZGJkNGYtM2U3MmQzOGItNjMwYTVlYzgtMzIzYjkzNGU=" } } } }
2025-12-04 21:00:58,918 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:00:58,918 - DEBUG - ydb.table.SessionPool.2486776  - put: Replying to waiter with a session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:00:58,918 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(CreateSession, a801d2c2-0822-47a7-bc23-d637e1b9d4a2, ghrun-if77zlumgq.auto.internal:9740): on response callback success
2025-12-04 21:00:58,919 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(CreateTable, ebe5138e-c75d-4a45-9b97-7cf0a1df1cc1, ghrun-if77zlumgq.auto.internal:9740): creating call state
2025-12-04 21:00:58,920 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(CreateTable, ebe5138e-c75d-4a45-9b97-7cf0a1df1cc1, ghrun-if77zlumgq.auto.internal:9740): request = { session_id: "ydb://session/3?node_id=50000&id=YzQ1ZGJkNGYtM2U3MmQzOGItNjMwYTVlYzgtMzIzYjkzNGU=" path: "/Root/database_0/table_0" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" profile { partitioning_policy { uniform_partitions: 10 } } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:00:59,013 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(CreateTable, ebe5138e-c75d-4a45-9b97-7cf0a1df1cc1, ghrun-if77zlumgq.auto.internal:9740): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:00:59,014 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:00:59,014 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(DescribeTable, e0bf00f4-44c8-47b2-9707-a54e3cf22e1b, ghrun-if77zlumgq.auto.internal:9740): creating call state
2025-12-04 21:00:59,015 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(DescribeTable, e0bf00f4-44c8-47b2-9707-a54e3cf22e1b, ghrun-if77zlumgq.auto.internal:9740): request = { session_id: "ydb://session/3?node_id=50000&id=YzQ1ZGJkNGYtM2U3MmQzOGItNjMwYTVlYzgtMzIzYjkzNGU=" path: "/Root/database_0/table_0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:00:59,032 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(DescribeTable, e0bf00f4-44c8-47b2-9707-a54e3cf22e1b, ghrun-if77zlumgq.auto.internal:9740): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table_0" 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: 1764882059040 tx_id: 281474976720659 } } columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } 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: 10 } } } } }
2025-12-04 21:00:59,033 - DEBUG - __tests__.test_tenants.2486776  - describe_table: > describe table: series, <ydb.table.TableSchemeEntry object at 0x7ffae739c0e0>
2025-12-04 21:00:59,033 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:00:59,034 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_0: remove path and dismantle internals
2025-12-04 21:00:59,034 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - remove_database: /Root/database_0
2025-12-04 21:00:59,034 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_0: send console operation, token None
2025-12-04 21:00:59,038 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_0: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/database_0&cmstid=72057594037936131&txid=1764882059036151&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 21:00:59,038 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_0: wait console operation done
2025-12-04 21:00:59,542 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_0: console operation done
2025-12-04 21:00:59,542 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_0: wait tenant gone
2025-12-04 21:00:59,545 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_0: tenant gone
2025-12-04 21:00:59,545 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_0: stop nodes
2025-12-04 21:01:11,546 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - stop: Stopped node localhost:9740/1
2025-12-04 21:01:11,547 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_0: database down
2025-12-04 21:01:11,547 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - remove_database: /Root/database_1
2025-12-04 21:01:11,547 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_1: send console operation, token None
2025-12-04 21:01:11,552 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_1: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/database_1\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/database_1\' doesn\'t exist"
}

2025-12-04 21:01:11,552 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_1: wait console operation done
2025-12-04 21:01:12,059 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_1: console operation done
2025-12-04 21:01:12,060 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_1: wait tenant gone
2025-12-04 21:01:12,062 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_1: tenant gone
2025-12-04 21:01:12,062 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_1: create path and declare internals
2025-12-04 21:01:12,572 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_1: start nodes and construct internals
2025-12-04 21:01:12,573 - DEBUG - ya.test.2486776  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd
2025-12-04 21:01:12,573 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64252
2025-12-04 21:01:12,574 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15715
2025-12-04 21:01:12,574 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26134
2025-12-04 21:01:12,574 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11239
2025-12-04 21:01:12,574 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63422
2025-12-04 21:01:12,575 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:01:12,575 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:63373 --tenant=/Root/database_1 --log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_k_ixyec7.log --grpc-port=64252 --mon-port=15715 --ic-port=26134
2025-12-04 21:01:12,576 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:01:12,576 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:63373 --tenant=/Root/database_1 --log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_k_ixyec7.log --grpc-port=64252 --mon-port=15715 --ic-port=26134
2025-12-04 21:01:12,577 - DEBUG - ya.test.2486776  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:63373', '--tenant=/Root/database_1', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_k_ixyec7.log', '--grpc-port=64252', '--mon-port=15715', '--ic-port=26134')' in '/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-12-04 21:01:12,578 - DEBUG - ya.test.2486776  - execute: Command pid: 2504468
2025-12-04 21:01:12,578 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Started node localhost:64252/2
2025-12-04 21:01:12,579 - DEBUG - ya.test.2486776  - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-12-04 21:01:12,579 - DEBUG - ya.test.2486776  - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-12-04 21:01:12,580 - DEBUG - ya.test.2486776  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:01:12,580 - DEBUG - ya.test.2486776  - execute: Command pid: 2504469
2025-12-04 21:01:13,409 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) rc: 0
2025-12-04 21:01:13,409 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) elapsed time (sec): 0.8282504081726074
2025-12-04 21:01:13,410 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) idrss: 0
2025-12-04 21:01:13,410 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) inblock: 0
2025-12-04 21:01:13,410 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) isrss: 0
2025-12-04 21:01:13,410 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) ixrss: 0
2025-12-04 21:01:13,410 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) majflt: 2
2025-12-04 21:01:13,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) maxrss: 546940
2025-12-04 21:01:13,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) minflt: 17681
2025-12-04 21:01:13,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) msgrcv: 0
2025-12-04 21:01:13,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) msgsnd: 0
2025-12-04 21:01:13,411 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) nivcsw: 11
2025-12-04 21:01:13,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) nsignals: 0
2025-12-04 21:01:13,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) nswap: 0
2025-12-04 21:01:13,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) nvcsw: 62
2025-12-04 21:01:13,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) oublock: 8
2025-12-04 21:01:13,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) stime: 0.686928
2025-12-04 21:01:13,412 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) utime: 0.09132599999999999
2025-12-04 21:01:13,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) wtime: 0.829
2025-12-04 21:01:13,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:01:13,413 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2504469) errors:

2025-12-04 21:01:13,413 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Node 2 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:01:13,414 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_1: wait construction done
2025-12-04 21:01:15,421 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_1: database up
2025-12-04 21:01:15,421 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:01:15,423 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:01:15,424 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 21:01:15,424 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:01:15,425 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Subscribe waiter <Future at 0x7ffae8d76a40 state=pending>
2025-12-04 21:01:15,429 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 21:01:15,429 - DEBUG - ydb.table.SessionPool.2486776  - _create: Created session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:01:15,429 - DEBUG - ydb.table.SessionPool.2486776  - _prepare: Preparing session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:01:15,430 - ERROR - ydb.table.SessionPool.2486776  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 21:01:15,434 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Resolving endpoints for database /Root/database_1
2025-12-04 21:01:15,435 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(ListEndpoints, 0ea27b67-c361-4771-b212-4d204b3ee687, localhost:63373): creating call state
2025-12-04 21:01:15,435 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(ListEndpoints, 0ea27b67-c361-4771-b212-4d204b3ee687, localhost:63373): request = { database: "/Root/database_1" }
2025-12-04 21:01:15,438 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(ListEndpoints, 0ea27b67-c361-4771-b212-4d204b3ee687, localhost:63373): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-if77zlumgq.auto.internal" port: 64252 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50001 } self_location: "1" } } } }
2025-12-04 21:01:15,438 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - _add_debug_details: Resolved endpoints for database /Root/database_1: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-if77zlumgq.auto.internal:64252, location , ssl: False>]>
2025-12-04 21:01:15,438 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:01:15,450 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:01:15,450 - DEBUG - ydb.table.SessionPool.2486776  - _prepare: Preparing session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:01:15,451 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(CreateSession, 424e14f1-c363-4221-b1cb-3ecebdd492ab, ghrun-if77zlumgq.auto.internal:64252): creating call state
2025-12-04 21:01:15,451 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(CreateSession, 424e14f1-c363-4221-b1cb-3ecebdd492ab, ghrun-if77zlumgq.auto.internal:64252): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:01:15,454 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(CreateSession, 424e14f1-c363-4221-b1cb-3ecebdd492ab, ghrun-if77zlumgq.auto.internal:64252): on response callback started
2025-12-04 21:01:15,455 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(CreateSession, 424e14f1-c363-4221-b1cb-3ecebdd492ab, ghrun-if77zlumgq.auto.internal:64252): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=Y2Q2YWRiZGMtNTFmNGRlYjQtYTZhMTFjODctM2EwNWMxOWU=" } } } }
2025-12-04 21:01:15,455 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:01:15,455 - DEBUG - ydb.table.SessionPool.2486776  - put: Replying to waiter with a session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:01:15,456 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(CreateSession, 424e14f1-c363-4221-b1cb-3ecebdd492ab, ghrun-if77zlumgq.auto.internal:64252): on response callback success
2025-12-04 21:01:15,456 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(CreateTable, 1f9ce524-11b3-48d1-ae99-27cbbd1b3f41, ghrun-if77zlumgq.auto.internal:64252): creating call state
2025-12-04 21:01:15,457 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(CreateTable, 1f9ce524-11b3-48d1-ae99-27cbbd1b3f41, ghrun-if77zlumgq.auto.internal:64252): request = { session_id: "ydb://session/3?node_id=50001&id=Y2Q2YWRiZGMtNTFmNGRlYjQtYTZhMTFjODctM2EwNWMxOWU=" path: "/Root/database_1/table_0" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" profile { partitioning_policy { uniform_partitions: 10 } } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:01:15,511 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(CreateTable, 1f9ce524-11b3-48d1-ae99-27cbbd1b3f41, ghrun-if77zlumgq.auto.internal:64252): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:01:15,512 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:01:15,512 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(DescribeTable, b93cbd80-fda4-47ec-b7a3-bad44d2a116e, ghrun-if77zlumgq.auto.internal:64252): creating call state
2025-12-04 21:01:15,513 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(DescribeTable, b93cbd80-fda4-47ec-b7a3-bad44d2a116e, ghrun-if77zlumgq.auto.internal:64252): request = { session_id: "ydb://session/3?node_id=50001&id=Y2Q2YWRiZGMtNTFmNGRlYjQtYTZhMTFjODctM2EwNWMxOWU=" path: "/Root/database_1/table_0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:01:15,532 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(DescribeTable, b93cbd80-fda4-47ec-b7a3-bad44d2a116e, ghrun-if77zlumgq.auto.internal:64252): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table_0" 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: 1764882075550 tx_id: 281474976730659 } } columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } 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: 10 } } } } }
2025-12-04 21:01:15,533 - DEBUG - __tests__.test_tenants.2486776  - describe_table: > describe table: series, <ydb.table.TableSchemeEntry object at 0x7ffae73239e0>
2025-12-04 21:01:15,534 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:01:15,534 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_1: remove path and dismantle internals
2025-12-04 21:01:15,534 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - remove_database: /Root/database_1
2025-12-04 21:01:15,534 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_1: send console operation, token None
2025-12-04 21:01:15,539 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_1: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/database_1&cmstid=72057594037936131&txid=1764882075536882&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 21:01:15,539 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_1: wait console operation done
2025-12-04 21:01:16,043 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_1: console operation done
2025-12-04 21:01:16,044 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_1: wait tenant gone
2025-12-04 21:01:16,048 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_1: tenant gone
2025-12-04 21:01:16,049 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_1: stop nodes
2025-12-04 21:01:38,057 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - stop: Stopped node localhost:64252/2
2025-12-04 21:01:38,058 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_1: database down
2025-12-04 21:01:38,058 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - remove_database: /Root/database_2
2025-12-04 21:01:38,058 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_2: send console operation, token None
2025-12-04 21:01:38,063 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_2: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/database_2\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/database_2\' doesn\'t exist"
}

2025-12-04 21:01:38,063 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_2: wait console operation done
2025-12-04 21:01:38,568 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_2: console operation done
2025-12-04 21:01:38,568 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_2: wait tenant gone
2025-12-04 21:01:38,571 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_2: tenant gone
2025-12-04 21:01:38,571 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_2: create path and declare internals
2025-12-04 21:01:39,086 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_2: start nodes and construct internals
2025-12-04 21:01:39,087 - DEBUG - ya.test.2486776  - get_binary: Binary was found by /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd
2025-12-04 21:01:39,089 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13153
2025-12-04 21:01:39,092 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8182
2025-12-04 21:01:39,096 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4995
2025-12-04 21:01:39,098 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15861
2025-12-04 21:01:39,099 - DEBUG - library.python.filelock.2486776  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30282
2025-12-04 21:01:39,100 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:01:39,100 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:63373 --tenant=/Root/database_2 --log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_3pckw1k9.log --grpc-port=13153 --mon-port=8182 --ic-port=4995
2025-12-04 21:01:39,101 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-12-04 21:01:39,107 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - __make_run_command: Final command: /home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:63373 --tenant=/Root/database_2 --log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_3pckw1k9.log --grpc-port=13153 --mon-port=8182 --ic-port=4995
2025-12-04 21:01:39,113 - DEBUG - ya.test.2486776  - execute: Executing '('/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:63373', '--tenant=/Root/database_2', '--log-file-name=/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_3pckw1k9.log', '--grpc-port=13153', '--mon-port=8182', '--ic-port=4995')' in '/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_tenants.py.TestTenants.test_when_deactivate_fat_tenant_creation_another_tenant_is_ok.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-12-04 21:01:39,114 - DEBUG - ya.test.2486776  - execute: Command pid: 2524020
2025-12-04 21:01:39,115 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Started node localhost:13153/3
2025-12-04 21:01:39,116 - DEBUG - ya.test.2486776  - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-12-04 21:01:39,118 - DEBUG - ya.test.2486776  - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-12-04 21:01:39,118 - DEBUG - ya.test.2486776  - execute: Executing '['/home/runner/.ya/build/build_root/6mdl/004fcb/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:01:39,139 - DEBUG - ya.test.2486776  - execute: Command pid: 2524021
2025-12-04 21:01:39,844 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) rc: 0
2025-12-04 21:01:39,844 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) elapsed time (sec): 0.7042908668518066
2025-12-04 21:01:39,844 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) idrss: 0
2025-12-04 21:01:39,845 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) inblock: 0
2025-12-04 21:01:39,845 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) isrss: 0
2025-12-04 21:01:39,845 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) ixrss: 0
2025-12-04 21:01:39,847 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) majflt: 0
2025-12-04 21:01:39,849 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) maxrss: 546892
2025-12-04 21:01:39,850 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) minflt: 16261
2025-12-04 21:01:39,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) msgrcv: 0
2025-12-04 21:01:39,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) msgsnd: 0
2025-12-04 21:01:39,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) nivcsw: 259
2025-12-04 21:01:39,852 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) nsignals: 0
2025-12-04 21:01:39,853 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) nswap: 0
2025-12-04 21:01:39,855 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) nvcsw: 17
2025-12-04 21:01:39,855 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) oublock: 8
2025-12-04 21:01:39,855 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) stime: 0.38007599999999997
2025-12-04 21:01:39,856 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) utime: 0.120183
2025-12-04 21:01:39,856 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) wtime: 0.725
2025-12-04 21:01:39,856 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-04 21:01:39,858 - DEBUG - ya.test.2486776  - _save_outputs: Command (pid 2524021) errors:

2025-12-04 21:01:39,859 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - start: Node 3 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-if77zlumgq    Host information: 
        Linux ghrun-if77zlumgq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:01:39,859 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_2: wait construction done
2025-12-04 21:01:41,867 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: create database /Root/database_2: database up
2025-12-04 21:01:41,868 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:01:41,870 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:01:41,871 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-12-04 21:01:41,871 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:01:41,872 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Subscribe waiter <Future at 0x7ffae8d68970 state=pending>
2025-12-04 21:01:41,876 - DEBUG - ydb.table.SessionPool.2486776  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-12-04 21:01:41,876 - DEBUG - ydb.table.SessionPool.2486776  - _create: Created session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:01:41,877 - DEBUG - ydb.table.SessionPool.2486776  - _prepare: Preparing session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:01:41,878 - ERROR - ydb.table.SessionPool.2486776  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-12-04 21:01:41,879 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Resolving endpoints for database /Root/database_2
2025-12-04 21:01:41,879 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(ListEndpoints, ae12752d-10df-4fc4-abc1-cc367069f781, localhost:63373): creating call state
2025-12-04 21:01:41,880 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(ListEndpoints, ae12752d-10df-4fc4-abc1-cc367069f781, localhost:63373): request = { database: "/Root/database_2" }
2025-12-04 21:01:41,887 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(ListEndpoints, ae12752d-10df-4fc4-abc1-cc367069f781, localhost:63373): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-if77zlumgq.auto.internal" port: 13153 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50002 } self_location: "1" } } } }
2025-12-04 21:01:41,888 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - _add_debug_details: Resolved endpoints for database /Root/database_2: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-if77zlumgq.auto.internal:13153, location , ssl: False>]>
2025-12-04 21:01:41,888 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:01:41,896 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:01:41,896 - DEBUG - ydb.table.SessionPool.2486776  - _prepare: Preparing session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:01:41,897 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(CreateSession, 5dd9f6ef-a8ff-44cc-a213-8354251d3990, ghrun-if77zlumgq.auto.internal:13153): creating call state
2025-12-04 21:01:41,898 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(CreateSession, 5dd9f6ef-a8ff-44cc-a213-8354251d3990, ghrun-if77zlumgq.auto.internal:13153): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:01:41,900 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(CreateSession, 5dd9f6ef-a8ff-44cc-a213-8354251d3990, ghrun-if77zlumgq.auto.internal:13153): on response callback started
2025-12-04 21:01:41,901 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(CreateSession, 5dd9f6ef-a8ff-44cc-a213-8354251d3990, ghrun-if77zlumgq.auto.internal:13153): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=ZGRjZjg2ZGMtMTkzZTZjNzYtNzI2ODBiMTYtZjM2YTNjNzk=" } } } }
2025-12-04 21:01:41,902 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:01:41,903 - DEBUG - ydb.table.SessionPool.2486776  - put: Replying to waiter with a session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:01:41,903 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(CreateTable, a70beb51-5279-4097-97cd-f67fd9f93de7, ghrun-if77zlumgq.auto.internal:13153): creating call state
2025-12-04 21:01:41,904 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(CreateTable, a70beb51-5279-4097-97cd-f67fd9f93de7, ghrun-if77zlumgq.auto.internal:13153): request = { session_id: "ydb://session/3?node_id=50002&id=ZGRjZjg2ZGMtMTkzZTZjNzYtNzI2ODBiMTYtZjM2YTNjNzk=" path: "/Root/database_2/table_0" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } primary_key: "id" profile { partitioning_policy { uniform_partitions: 10 } } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:01:41,904 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(CreateSession, 5dd9f6ef-a8ff-44cc-a213-8354251d3990, ghrun-if77zlumgq.auto.internal:13153): on response callback success
2025-12-04 21:01:41,959 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(CreateTable, a70beb51-5279-4097-97cd-f67fd9f93de7, ghrun-if77zlumgq.auto.internal:13153): response = { operation { ready: true status: SUCCESS } }
2025-12-04 21:01:41,967 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:01:41,970 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(DescribeTable, 9ec3d8ef-b284-446f-840a-685c9aff037d, ghrun-if77zlumgq.auto.internal:13153): creating call state
2025-12-04 21:01:41,971 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(DescribeTable, 9ec3d8ef-b284-446f-840a-685c9aff037d, ghrun-if77zlumgq.auto.internal:13153): request = { session_id: "ydb://session/3?node_id=50002&id=ZGRjZjg2ZGMtMTkzZTZjNzYtNzI2ODBiMTYtZjM2YTNjNzk=" path: "/Root/database_2/table_0" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-12-04 21:01:41,992 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(DescribeTable, 9ec3d8ef-b284-446f-840a-685c9aff037d, ghrun-if77zlumgq.auto.internal:13153): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.DescribeTableResult] { self { name: "table_0" 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: 1764882101990 tx_id: 281474976740659 } } columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } 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: 10 } } } } }
2025-12-04 21:01:41,993 - DEBUG - __tests__.test_tenants.2486776  - describe_table: > describe table: series, <ydb.table.TableSchemeEntry object at 0x7ffae7353460>
2025-12-04 21:01:41,993 - DEBUG - ydb.table.SessionPool.2486776  - put: Put on session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:01:41,993 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_2: remove path and dismantle internals
2025-12-04 21:01:41,994 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - remove_database: /Root/database_2
2025-12-04 21:01:41,994 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_2: send console operation, token None
2025-12-04 21:01:41,999 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_send_op: /Root/database_2: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/database_2&cmstid=72057594037936131&txid=1764882101996038&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-12-04 21:01:41,999 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_2: wait console operation done
2025-12-04 21:01:42,509 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_op: /Root/database_2: console operation done
2025-12-04 21:01:42,510 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_2: wait tenant gone
2025-12-04 21:01:42,513 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.2486776  - _remove_database_wait_tenant_gone: /Root/database_2: tenant gone
2025-12-04 21:01:42,514 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_2: stop nodes
2025-12-04 21:02:04,519 - INFO - ydb.tests.library.harness.kikimr_runner.2486776  - stop: Stopped node localhost:13153/3
2025-12-04 21:02:04,520 - DEBUG - ydb.tests.library.fixtures.2486776  - ydb_database_ctx: destroy database /Root/database_2: database down
2025-12-04 21:02:04,521 - INFO - ya.test.2486776  - pytest_runtest_teardown: Test teardown
2025-12-04 21:02:04,521 - DEBUG - ydb.table.SessionPool.2486776  - stop: Requested session pool stop.
2025-12-04 21:02:04,522 - DEBUG - ydb.table.SessionPool.2486776  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 21:02:04,522 - DEBUG - ydb.table.SessionPool.2486776  - stop: Destroying sessions in active queue
2025-12-04 21:02:04,522 - DEBUG - ydb.table.SessionPool.2486776  - _terminate_event: Terminated session pool.
2025-12-04 21:02:04,523 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7ffae8d70c90>, reason: session-pool-terminated
2025-12-04 21:02:04,524 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Session <ydb.table.Session object at 0x7ffae8d70c90> is no longer active. Current active count 0.
2025-12-04 21:02:04,524 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(DeleteSession, b673656c-5b9b-4403-8433-610fd315bfe8, ghrun-if77zlumgq.auto.internal:13153): creating call state
2025-12-04 21:02:04,525 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(DeleteSession, b673656c-5b9b-4403-8433-610fd315bfe8, ghrun-if77zlumgq.auto.internal:13153): request = { session_id: "ydb://session/3?node_id=50002&id=ZGRjZjg2ZGMtMTkzZTZjNzYtNzI2ODBiMTYtZjM2YTNjNzk=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:02:04,526 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Sent delete on session <ydb.table.Session object at 0x7ffae8d70c90>
2025-12-04 21:02:04,526 - DEBUG - ydb.table.SessionPool.2486776  - stop: Destroyed active sessions
2025-12-04 21:02:04,526 - DEBUG - ydb.table.SessionPool.2486776  - stop: Requested session pool stop.
2025-12-04 21:02:04,526 - DEBUG - ydb.table.SessionPool.2486776  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 21:02:04,527 - DEBUG - ydb.table.SessionPool.2486776  - _terminate_event: Terminated session pool.
2025-12-04 21:02:04,527 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(DeleteSession, b673656c-5b9b-4403-8433-610fd315bfe8, ghrun-if77zlumgq.auto.internal:13153): on response callback started
2025-12-04 21:02:04,527 - DEBUG - ydb.table.SessionPool.2486776  - stop: Destroying sessions in active queue
2025-12-04 21:02:04,529 - DEBUG - ydb.connection.2486776  - _rpc_error_handler: RpcState(DeleteSession, b673656c-5b9b-4403-8433-610fd315bfe8, ghrun-if77zlumgq.auto.internal:13153): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:13153: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:13153: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T21:02:04.526402103+00:00", grpc_status:14}"
>
2025-12-04 21:02:04,530 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7ffae8d7a770>, reason: session-pool-terminated
2025-12-04 21:02:04,530 - DEBUG - ydb.connection.2486776  - _rpc_error_handler: RpcState(DeleteSession, b673656c-5b9b-4403-8433-610fd315bfe8, ghrun-if77zlumgq.auto.internal:13153): unhandled rpc error, disconnecting channel
2025-12-04 21:02:04,530 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Session <ydb.table.Session object at 0x7ffae8d7a770> is no longer active. Current active count 0.
2025-12-04 21:02:04,531 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint ghrun-if77zlumgq.auto.internal:13153
2025-12-04 21:02:04,531 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(DeleteSession, b7aee958-b80d-4619-9ba0-289f3aa16734, ghrun-if77zlumgq.auto.internal:64252): creating call state
2025-12-04 21:02:04,532 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:02:04,533 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(DeleteSession, b7aee958-b80d-4619-9ba0-289f3aa16734, ghrun-if77zlumgq.auto.internal:64252): request = { session_id: "ydb://session/3?node_id=50001&id=Y2Q2YWRiZGMtNTFmNGRlYjQtYTZhMTFjODctM2EwNWMxOWU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:02:04,533 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:02:04,536 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Sent delete on session <ydb.table.Session object at 0x7ffae8d7a770>
2025-12-04 21:02:04,537 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(DeleteSession, b7aee958-b80d-4619-9ba0-289f3aa16734, ghrun-if77zlumgq.auto.internal:64252): on response callback started
2025-12-04 21:02:04,537 - DEBUG - ydb.table.SessionPool.2486776  - stop: Destroyed active sessions
2025-12-04 21:02:04,538 - DEBUG - ydb.connection.2486776  - _rpc_error_handler: RpcState(DeleteSession, b7aee958-b80d-4619-9ba0-289f3aa16734, ghrun-if77zlumgq.auto.internal:64252): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:64252: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:64252: Failed to connect to remote host: Connection refused {file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc", file_line:153, created_time:"2025-12-04T21:02:04.533976198+00:00", grpc_status:14}"
>
2025-12-04 21:02:04,538 - DEBUG - ydb.table.SessionPool.2486776  - stop: Requested session pool stop.
2025-12-04 21:02:04,539 - DEBUG - ydb.connection.2486776  - _rpc_error_handler: RpcState(DeleteSession, b7aee958-b80d-4619-9ba0-289f3aa16734, ghrun-if77zlumgq.auto.internal:64252): unhandled rpc error, disconnecting channel
2025-12-04 21:02:04,540 - DEBUG - ydb.table.SessionPool.2486776  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-12-04 21:02:04,540 - DEBUG - ydb.table.SessionPool.2486776  - _terminate_event: Terminated session pool.
2025-12-04 21:02:04,540 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Resolving endpoints for database /Root/database_2
2025-12-04 21:02:04,541 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint ghrun-if77zlumgq.auto.internal:64252
2025-12-04 21:02:04,541 - DEBUG - ydb.table.SessionPool.2486776  - stop: Destroying sessions in active queue
2025-12-04 21:02:04,542 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(ListEndpoints, 64d9d616-d6a3-49ab-a65a-bf91fd11ae0b, localhost:63373): creating call state
2025-12-04 21:02:04,543 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:02:04,543 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7ffae7227300>, reason: session-pool-terminated
2025-12-04 21:02:04,543 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(ListEndpoints, 64d9d616-d6a3-49ab-a65a-bf91fd11ae0b, localhost:63373): request = { database: "/Root/database_2" }
2025-12-04 21:02:04,544 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:02:04,544 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Session <ydb.table.Session object at 0x7ffae7227300> is no longer active. Current active count 0.
2025-12-04 21:02:04,548 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(DeleteSession, 2b6cb5b5-6fdb-4c03-86c1-6a4a0eb6aad7, ghrun-if77zlumgq.auto.internal:9740): creating call state
2025-12-04 21:02:04,549 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(ListEndpoints, 64d9d616-d6a3-49ab-a65a-bf91fd11ae0b, localhost:63373): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 21:02:04,550 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(DeleteSession, 2b6cb5b5-6fdb-4c03-86c1-6a4a0eb6aad7, ghrun-if77zlumgq.auto.internal:9740): request = { session_id: "ydb://session/3?node_id=50000&id=YzQ1ZGJkNGYtM2U3MmQzOGItNjMwYTVlYzgtMzIzYjkzNGU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-12-04 21:02:04,550 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - _add_debug_details: Failed to resolve endpoints for database /Root/database_2. Endpoint: "localhost:63373". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 21:02:04,551 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Resolving endpoints for database /Root/database_1
2025-12-04 21:02:04,551 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:02:04,552 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(ListEndpoints, d871c901-7065-4643-809c-eeda6d688c63, localhost:63373): creating call state
2025-12-04 21:02:04,552 - DEBUG - ydb.table.SessionPool.2486776  - _destroy: Sent delete on session <ydb.table.Session object at 0x7ffae7227300>
2025-12-04 21:02:04,553 - DEBUG - ydb.connection.2486776  - _on_response_callback: RpcState(DeleteSession, 2b6cb5b5-6fdb-4c03-86c1-6a4a0eb6aad7, ghrun-if77zlumgq.auto.internal:9740): on response callback started
2025-12-04 21:02:04,553 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(ListEndpoints, d871c901-7065-4643-809c-eeda6d688c63, localhost:63373): request = { database: "/Root/database_1" }
2025-12-04 21:02:04,554 - DEBUG - ydb.table.SessionPool.2486776  - stop: Destroyed active sessions
2025-12-04 21:02:04,554 - DEBUG - ydb.connection.2486776  - _rpc_error_handler: RpcState(DeleteSession, 2b6cb5b5-6fdb-4c03-86c1-6a4a0eb6aad7, ghrun-if77zlumgq.auto.internal:9740): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:9740: Failed to connect to remote host: Connection refused"
	debug_error_string = "UNKNOWN:connections to all backends failing; last error: UNKNOWN: ipv4:127.0.1.1:9740: Failed to connect to remote host: Connection refused {grpc_status:14, created_time:"2025-12-04T21:02:04.551780542+00:00", file_line:153, file:"contrib/libs/grpc/src/core/lib/transport/error_utils.cc"}"
>
2025-12-04 21:02:04,555 - DEBUG - ydb.topic.2486776  - close: Close topic client
2025-12-04 21:02:04,555 - DEBUG - ydb.connection.2486776  - _rpc_error_handler: RpcState(DeleteSession, 2b6cb5b5-6fdb-4c03-86c1-6a4a0eb6aad7, ghrun-if77zlumgq.auto.internal:9740): unhandled rpc error, disconnecting channel
2025-12-04 21:02:04,556 - DEBUG - ydb.topic.2486776  - close: Topic client was closed
2025-12-04 21:02:04,556 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint ghrun-if77zlumgq.auto.internal:9740
2025-12-04 21:02:04,556 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:02:04,557 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(ListEndpoints, d871c901-7065-4643-809c-eeda6d688c63, localhost:63373): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 21:02:04,557 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:02:04,558 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - _add_debug_details: Failed to resolve endpoints for database /Root/database_1. Endpoint: "localhost:63373". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 21:02:04,559 - DEBUG - ydb.connection.2486776  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-04 21:02:04,559 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:02:04,562 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - context_resolve: Resolving endpoints for database /Root/database_0
2025-12-04 21:02:04,562 - DEBUG - ydb.connection.2486776  - _prepare_call: RpcState(ListEndpoints, 7dda7361-e34e-445b-9b56-fa0081bfb4b3, localhost:63373): creating call state
2025-12-04 21:02:04,562 - DEBUG - ydb.connection.2486776  - _log_request: RpcState(ListEndpoints, 7dda7361-e34e-445b-9b56-fa0081bfb4b3, localhost:63373): request = { database: "/Root/database_0" }
2025-12-04 21:02:04,564 - DEBUG - ydb.connection.2486776  - _log_response: RpcState(ListEndpoints, 7dda7361-e34e-445b-9b56-fa0081bfb4b3, localhost:63373): response = { operation { ready: true status: NOT_FOUND issues { message: "Requested database does not exist" issue_code: 200202 severity: 1 } } }
2025-12-04 21:02:04,564 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2486776  - _add_debug_details: Failed to resolve endpoints for database /Root/database_0. Endpoint: "localhost:63373". Error details:
 message: "Requested database does not exist" issue_code: 200202 severity: 1 (server_code: 400140)
2025-12-04 21:02:04,565 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:02:04,743 - INFO - ydb.pool.Discovery.2486776  - run: Successfully terminated discovery process
2025-12-04 21:02:04,743 - DEBUG - ydb.topic.2486776  - close: Close topic client
2025-12-04 21:02:04,744 - DEBUG - ydb.topic.2486776  - close: Topic client was closed
2025-12-04 21:02:04,744 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:02:04,754 - INFO - ydb.pool.Discovery.2486776  - run: Successfully terminated discovery process
2025-12-04 21:02:04,754 - DEBUG - ydb.topic.2486776  - close: Close topic client
2025-12-04 21:02:04,755 - DEBUG - ydb.topic.2486776  - close: Topic client was closed
2025-12-04 21:02:04,755 - DEBUG - ydb.connection.2486776  - close: Closing channel for endpoint localhost:63373
2025-12-04 21:02:04,765 - INFO - ydb.pool.Discovery.2486776  - run: Successfully terminated discovery process
