2025-10-16 15:26:00,264 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 15:26:00,265 - INFO - ya.test - pytest_runtest_setup: test_create_and_drop_tenants[enable_alter_database_create_hive_first--true]
2025-10-16 15:26:00,265 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 15:26:00,265 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 15:26:00,360 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:00,361 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13824
2025-10-16 15:26:00,363 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster for __tests__.test_dynamic_tenants
2025-10-16 15:26:00,363 - INFO - ydb.tests.library.fixtures - ydb_cluster: setup ydb_cluster as local
2025-10-16 15:26:00,364 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/bn3t/000282/r3tmp/kikimr_cluster_qlvrfd2q
2025-10-16 15:26:00,415 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:00,416 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61697
2025-10-16 15:26:00,416 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8200
2025-10-16 15:26:00,417 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27609
2025-10-16 15:26:00,417 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27581
2025-10-16 15:26:00,418 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:00,418 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/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/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_6iiok7g2.log --grpc-port=61697 --mon-port=8200 --ic-port=13824
2025-10-16 15:26:00,419 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:61697/1, disk_size 68719476736
2025-10-16 15:26:00,419 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:00,419 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/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/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_6iiok7g2.log --grpc-port=61697 --mon-port=8200 --ic-port=13824
2025-10-16 15:26:00,420 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1/logfile_6iiok7g2.log', '--grpc-port=61697', '--mon-port=8200', '--ic-port=13824')' in '/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/node_1' (no wait)
2025-10-16 15:26:00,421 - DEBUG - ya.test - execute: Command pid: 4110814
2025-10-16 15:26:00,422 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:61697/1
2025-10-16 15:26:00,423 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-16 15:26:00,423 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-16 15:26:00,423 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 15:26:00,424 - DEBUG - ya.test - execute: Command pid: 4110827
2025-10-16 15:26:02,917 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) rc: 0
2025-10-16 15:26:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) elapsed time (sec): 2.4801461696624756
2025-10-16 15:26:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) idrss: 0
2025-10-16 15:26:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) inblock: 0
2025-10-16 15:26:02,919 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) isrss: 0
2025-10-16 15:26:02,919 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) ixrss: 0
2025-10-16 15:26:02,939 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) majflt: 0
2025-10-16 15:26:02,940 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) maxrss: 543052
2025-10-16 15:26:02,940 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) minflt: 16234
2025-10-16 15:26:02,940 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) msgrcv: 0
2025-10-16 15:26:02,940 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) msgsnd: 0
2025-10-16 15:26:02,941 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) nivcsw: 355
2025-10-16 15:26:02,941 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) nsignals: 0
2025-10-16 15:26:02,941 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) nswap: 0
2025-10-16 15:26:02,941 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) nvcsw: 30
2025-10-16 15:26:02,941 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) oublock: 8
2025-10-16 15:26:02,941 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) stime: 1.090321
2025-10-16 15:26:02,942 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) utime: 0.13580499999999998
2025-10-16 15:26:02,942 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) wtime: 2.481
2025-10-16 15:26:02,942 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) output:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-isvtmfzgna
    Host information: 
        Linux ghrun-isvtmfzgna 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-16 15:26:02,942 - DEBUG - ya.test - _save_outputs: Command (pid 4110827) errors:

2025-10-16 15:26:02,943 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7
2025-10-16 15:26:07,729 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:07,730 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61697', '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: 13824\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-16 15:26:07,733 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-16 15:26:07,733 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-16 15:26:07,734 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61697', '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: 13824\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 15:26:07,740 - DEBUG - ya.test - execute: Command pid: 4115527
2025-10-16 15:26:12,086 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) rc: 0
2025-10-16 15:26:12,086 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) elapsed time (sec): 4.345332860946655
2025-10-16 15:26:12,086 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) idrss: 0
2025-10-16 15:26:12,086 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) inblock: 0
2025-10-16 15:26:12,087 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) isrss: 0
2025-10-16 15:26:12,087 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) ixrss: 0
2025-10-16 15:26:12,087 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) majflt: 0
2025-10-16 15:26:12,087 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) maxrss: 551392
2025-10-16 15:26:12,087 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) minflt: 19061
2025-10-16 15:26:12,087 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) msgrcv: 0
2025-10-16 15:26:12,087 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) msgsnd: 0
2025-10-16 15:26:12,088 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) nivcsw: 768
2025-10-16 15:26:12,088 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) nsignals: 0
2025-10-16 15:26:12,088 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) nswap: 0
2025-10-16 15:26:12,088 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) nvcsw: 137
2025-10-16 15:26:12,088 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) oublock: 8
2025-10-16 15:26:12,088 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) stime: 2.349256
2025-10-16 15:26:12,088 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) utime: 0.123904
2025-10-16 15:26:12,089 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) wtime: 4.351
2025-10-16 15:26:12,089 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-16 15:26:12,089 - DEBUG - ya.test - _save_outputs: Command (pid 4115527) errors:

2025-10-16 15:26:12,090 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:12,090 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61697', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-16 15:26:12,091 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-16 15:26:12,100 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-16 15:26:12,100 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:61697', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 15:26:12,111 - DEBUG - ya.test - execute: Command pid: 4117701
2025-10-16 15:26:13,703 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) rc: 0
2025-10-16 15:26:13,703 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) elapsed time (sec): 1.58662748336792
2025-10-16 15:26:13,703 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) idrss: 0
2025-10-16 15:26:13,704 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) inblock: 0
2025-10-16 15:26:13,706 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) isrss: 0
2025-10-16 15:26:13,715 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) ixrss: 0
2025-10-16 15:26:13,725 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) majflt: 0
2025-10-16 15:26:13,726 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) maxrss: 551404
2025-10-16 15:26:13,726 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) minflt: 18371
2025-10-16 15:26:13,726 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) msgrcv: 0
2025-10-16 15:26:13,726 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) msgsnd: 0
2025-10-16 15:26:13,726 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) nivcsw: 630
2025-10-16 15:26:13,726 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) nsignals: 0
2025-10-16 15:26:13,727 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) nswap: 0
2025-10-16 15:26:13,731 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) nvcsw: 95
2025-10-16 15:26:13,731 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) oublock: 8
2025-10-16 15:26:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) stime: 0.9185329999999999
2025-10-16 15:26:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) utime: 0.11841
2025-10-16 15:26:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) wtime: 1.598
2025-10-16 15:26:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-16 15:26:13,734 - DEBUG - ya.test - _save_outputs: Command (pid 4117701) errors:

2025-10-16 15:26:13,734 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-16 15:26:13,782 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-16 15:26:13,824 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_dynamic_tenants.py, test_name: test_create_and_drop_tenants[enable_alter_database_create_hive_first--true])
2025-10-16 15:26:14,350 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:14,368 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10697
2025-10-16 15:26:14,377 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15805
2025-10-16 15:26:14,378 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28011
2025-10-16 15:26:14,380 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62892
2025-10-16 15:26:14,380 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2913
2025-10-16 15:26:14,391 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:14,407 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_0 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_ktah6mec.log --grpc-port=10697 --mon-port=15805 --ic-port=28011
2025-10-16 15:26:14,408 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:14,408 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_0 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_ktah6mec.log --grpc-port=10697 --mon-port=15805 --ic-port=28011
2025-10-16 15:26:14,419 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:61697', '--tenant=/Root/users/database_0', '--log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1/logfile_ktah6mec.log', '--grpc-port=10697', '--mon-port=15805', '--ic-port=28011')' in '/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_1' (no wait)
2025-10-16 15:26:14,423 - DEBUG - ya.test - execute: Command pid: 4119385
2025-10-16 15:26:14,443 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10697/1
2025-10-16 15:26:14,455 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-16 15:26:14,485 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-16 15:26:14,487 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 15:26:14,488 - DEBUG - ya.test - execute: Command pid: 4119419
2025-10-16 15:26:16,293 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) rc: 0
2025-10-16 15:26:16,293 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) elapsed time (sec): 1.7847766876220703
2025-10-16 15:26:16,294 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) idrss: 0
2025-10-16 15:26:16,294 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) inblock: 0
2025-10-16 15:26:16,294 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) isrss: 0
2025-10-16 15:26:16,340 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) ixrss: 0
2025-10-16 15:26:16,340 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) majflt: 0
2025-10-16 15:26:16,340 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) maxrss: 543052
2025-10-16 15:26:16,341 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) minflt: 16227
2025-10-16 15:26:16,341 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) msgrcv: 0
2025-10-16 15:26:16,341 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) msgsnd: 0
2025-10-16 15:26:16,341 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) nivcsw: 330
2025-10-16 15:26:16,341 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) nsignals: 0
2025-10-16 15:26:16,341 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) nswap: 0
2025-10-16 15:26:16,341 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) nvcsw: 38
2025-10-16 15:26:16,342 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) oublock: 8
2025-10-16 15:26:16,342 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) stime: 0.7471909999999999
2025-10-16 15:26:16,342 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) utime: 0.131857
2025-10-16 15:26:16,390 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) wtime: 1.786
2025-10-16 15:26:16,390 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) output:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-isvtmfzgna
    Host information: 
        Linux ghrun-isvtmfzgna 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-16 15:26:16,390 - DEBUG - ya.test - _save_outputs: Command (pid 4119419) errors:

2025-10-16 15:26:16,391 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7
2025-10-16 15:26:18,419 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:18,429 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 15:26:18,435 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:18,438 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-16 15:26:18,451 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4881f260e0 state=pending>
2025-10-16 15:26:18,456 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-16 15:26:18,457 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,458 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,458 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-16 15:26:18,465 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_0
2025-10-16 15:26:18,466 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 8e95f851-58d7-40e0-a20e-a511a13d1d6c, localhost:61697): creating call state
2025-10-16 15:26:18,466 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 8e95f851-58d7-40e0-a20e-a511a13d1d6c, localhost:61697): request = { database: "/Root/users/database_0" }
2025-10-16 15:26:18,474 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 8e95f851-58d7-40e0-a20e-a511a13d1d6c, localhost:61697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-isvtmfzgna.auto.internal" port: 10697 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } self_location: "1" } } } }
2025-10-16 15:26:18,480 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_0: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-isvtmfzgna.auto.internal:10697, location , ssl: False>]>
2025-10-16 15:26:18,485 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:18,515 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:18,516 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,516 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, e7d3d040-c685-4c06-8166-24f54a5f8027, ghrun-isvtmfzgna.auto.internal:10697): creating call state
2025-10-16 15:26:18,517 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, e7d3d040-c685-4c06-8166-24f54a5f8027, ghrun-isvtmfzgna.auto.internal:10697): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:18,547 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e7d3d040-c685-4c06-8166-24f54a5f8027, ghrun-isvtmfzgna.auto.internal:10697): on response callback started
2025-10-16 15:26:18,560 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, e7d3d040-c685-4c06-8166-24f54a5f8027, ghrun-isvtmfzgna.auto.internal:10697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YzM4ODNmYmMtMzczMzJjNi1jMjA5MmUxNS1iODUzNWE1MQ==" } } } }
2025-10-16 15:26:18,565 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,565 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,566 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e7d3d040-c685-4c06-8166-24f54a5f8027, ghrun-isvtmfzgna.auto.internal:10697): on response callback success
2025-10-16 15:26:18,567 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 049839ee-1b78-41d6-962f-f4b1a1bc2b1a, ghrun-isvtmfzgna.auto.internal:10697): creating call state
2025-10-16 15:26:18,568 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 049839ee-1b78-41d6-962f-f4b1a1bc2b1a, ghrun-isvtmfzgna.auto.internal:10697): request = { session_id: "ydb://session/3?node_id=50000&id=YzM4ODNmYmMtMzczMzJjNi1jMjA5MmUxNS1iODUzNWE1MQ==" path: "/Root/users/database_0/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:18,738 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 049839ee-1b78-41d6-962f-f4b1a1bc2b1a, ghrun-isvtmfzgna.auto.internal:10697): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:18,738 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,739 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 1ddfdb65-e066-44f7-8ccd-f6ffef671bfa, ghrun-isvtmfzgna.auto.internal:10697): creating call state
2025-10-16 15:26:18,748 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 1ddfdb65-e066-44f7-8ccd-f6ffef671bfa, ghrun-isvtmfzgna.auto.internal:10697): request = { session_id: "ydb://session/3?node_id=50000&id=YzM4ODNmYmMtMzczMzJjNi1jMjA5MmUxNS1iODUzNWE1MQ==" path: "/Root/users/database_0/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:18,840 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 1ddfdb65-e066-44f7-8ccd-f6ffef671bfa, ghrun-isvtmfzgna.auto.internal:10697): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:18,848 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,850 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 8a3129f4-fe73-4ec7-b4e9-4b54fa871a0b, ghrun-isvtmfzgna.auto.internal:10697): creating call state
2025-10-16 15:26:18,850 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 8a3129f4-fe73-4ec7-b4e9-4b54fa871a0b, ghrun-isvtmfzgna.auto.internal:10697): request = { session_id: "ydb://session/3?node_id=50000&id=YzM4ODNmYmMtMzczMzJjNi1jMjA5MmUxNS1iODUzNWE1MQ==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_0\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_0\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:18,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 8a3129f4-fe73-4ec7-b4e9-4b54fa871a0b, ghrun-isvtmfzgna.auto.internal:10697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-16 15:26:18,923 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:18,959 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 794f78e6-361e-4acd-9690-4524a4b76cfa, ghrun-isvtmfzgna.auto.internal:10697): creating call state
2025-10-16 15:26:18,965 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 794f78e6-361e-4acd-9690-4524a4b76cfa, ghrun-isvtmfzgna.auto.internal:10697): request = { session_id: "ydb://session/3?node_id=50000&id=YzM4ODNmYmMtMzczMzJjNi1jMjA5MmUxNS1iODUzNWE1MQ==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:19,057 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 794f78e6-361e-4acd-9690-4524a4b76cfa, ghrun-isvtmfzgna.auto.internal:10697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_0" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-16 15:26:19,061 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:19,066 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 80f85f7d-a700-4399-9141-66a14f772e2c, ghrun-isvtmfzgna.auto.internal:10697): creating call state
2025-10-16 15:26:19,072 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 80f85f7d-a700-4399-9141-66a14f772e2c, ghrun-isvtmfzgna.auto.internal:10697): request = { session_id: "ydb://session/3?node_id=50000&id=YzM4ODNmYmMtMzczMzJjNi1jMjA5MmUxNS1iODUzNWE1MQ==" path: "/Root/users/database_0/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:19,129 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 80f85f7d-a700-4399-9141-66a14f772e2c, ghrun-isvtmfzgna.auto.internal:10697): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:19,130 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:19,130 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-16 15:26:19,131 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-16 15:26:19,132 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-16 15:26:19,132 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4883f9f080>, reason: session-pool-terminated
2025-10-16 15:26:19,132 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-16 15:26:19,142 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4883f9f080> is no longer active. Current active count 0.
2025-10-16 15:26:19,147 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c7cb1af1-f10a-4fd9-a01e-cc31e6db36e8, ghrun-isvtmfzgna.auto.internal:10697): creating call state
2025-10-16 15:26:19,147 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c7cb1af1-f10a-4fd9-a01e-cc31e6db36e8, ghrun-isvtmfzgna.auto.internal:10697): request = { session_id: "ydb://session/3?node_id=50000&id=YzM4ODNmYmMtMzczMzJjNi1jMjA5MmUxNS1iODUzNWE1MQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:19,167 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4883f9f080>
2025-10-16 15:26:19,190 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-16 15:26:19,190 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 15:26:19,190 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 15:26:19,191 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:19,191 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-isvtmfzgna.auto.internal:10697
2025-10-16 15:26:19,175 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, c7cb1af1-f10a-4fd9-a01e-cc31e6db36e8, ghrun-isvtmfzgna.auto.internal:10697): on response callback started
2025-10-16 15:26:19,191 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 15:26:19,196 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c7cb1af1-f10a-4fd9-a01e-cc31e6db36e8, ghrun-isvtmfzgna.auto.internal:10697): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:19,197 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_0
2025-10-16 15:26:19,199 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, c7cb1af1-f10a-4fd9-a01e-cc31e6db36e8, ghrun-isvtmfzgna.auto.internal:10697): on response callback success
2025-10-16 15:26:19,203 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_0: send console operation, token None
2025-10-16 15:26:19,224 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_0: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_0&cmstid=72057594037936131&txid=1760628379217860&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-16 15:26:19,240 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_0: wait console operation done
2025-10-16 15:26:19,749 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_0: console operation done
2025-10-16 15:26:19,750 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_0: wait tenant gone
2025-10-16 15:26:19,756 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_0: tenant gone
2025-10-16 15:26:22,767 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10697/1
2025-10-16 15:26:23,306 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:23,307 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15362
2025-10-16 15:26:23,318 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25367
2025-10-16 15:26:23,319 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16442
2025-10-16 15:26:23,319 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6522
2025-10-16 15:26:23,320 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23914
2025-10-16 15:26:23,320 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:23,321 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_1 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_uji11pij.log --grpc-port=15362 --mon-port=25367 --ic-port=16442
2025-10-16 15:26:23,321 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:23,321 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_1 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_uji11pij.log --grpc-port=15362 --mon-port=25367 --ic-port=16442
2025-10-16 15:26:23,324 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:61697', '--tenant=/Root/users/database_1', '--log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2/logfile_uji11pij.log', '--grpc-port=15362', '--mon-port=25367', '--ic-port=16442')' in '/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_2' (no wait)
2025-10-16 15:26:23,326 - DEBUG - ya.test - execute: Command pid: 4126332
2025-10-16 15:26:23,326 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:15362/2
2025-10-16 15:26:23,331 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-10-16 15:26:23,333 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-10-16 15:26:23,334 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 15:26:23,341 - DEBUG - ya.test - execute: Command pid: 4126333
2025-10-16 15:26:26,130 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) rc: 0
2025-10-16 15:26:26,131 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) elapsed time (sec): 2.7877144813537598
2025-10-16 15:26:26,131 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) idrss: 0
2025-10-16 15:26:26,131 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) inblock: 0
2025-10-16 15:26:26,131 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) isrss: 0
2025-10-16 15:26:26,131 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) ixrss: 0
2025-10-16 15:26:26,132 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) majflt: 0
2025-10-16 15:26:26,132 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) maxrss: 543052
2025-10-16 15:26:26,132 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) minflt: 16228
2025-10-16 15:26:26,132 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) msgrcv: 0
2025-10-16 15:26:26,132 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) msgsnd: 0
2025-10-16 15:26:26,133 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) nivcsw: 364
2025-10-16 15:26:26,133 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) nsignals: 0
2025-10-16 15:26:26,133 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) nswap: 0
2025-10-16 15:26:26,133 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) nvcsw: 63
2025-10-16 15:26:26,135 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) oublock: 8
2025-10-16 15:26:26,135 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) stime: 0.9545469999999999
2025-10-16 15:26:26,135 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) utime: 0.142335
2025-10-16 15:26:26,136 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) wtime: 2.795
2025-10-16 15:26:26,136 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) output:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-isvtmfzgna
    Host information: 
        Linux ghrun-isvtmfzgna 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-16 15:26:26,136 - DEBUG - ya.test - _save_outputs: Command (pid 4126333) errors:

2025-10-16 15:26:26,136 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 2 version:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7
2025-10-16 15:26:28,155 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:28,158 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 15:26:28,159 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:28,184 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_1
2025-10-16 15:26:28,184 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, f958eeed-0349-48de-aec2-769e1c47ce95, localhost:61697): creating call state
2025-10-16 15:26:28,185 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, f958eeed-0349-48de-aec2-769e1c47ce95, localhost:61697): request = { database: "/Root/users/database_1" }
2025-10-16 15:26:28,219 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-16 15:26:28,219 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4881f05ff0 state=pending>
2025-10-16 15:26:28,219 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-16 15:26:28,220 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,220 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,220 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-16 15:26:28,223 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, f958eeed-0349-48de-aec2-769e1c47ce95, localhost:61697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-isvtmfzgna.auto.internal" port: 15362 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50001 } self_location: "1" } } } }
2025-10-16 15:26:28,223 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_1: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-isvtmfzgna.auto.internal:15362, location , ssl: False>]>
2025-10-16 15:26:28,224 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:28,254 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:28,255 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,257 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2a5a5383-e8d9-4112-8cab-6d051c919fc8, ghrun-isvtmfzgna.auto.internal:15362): creating call state
2025-10-16 15:26:28,258 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2a5a5383-e8d9-4112-8cab-6d051c919fc8, ghrun-isvtmfzgna.auto.internal:15362): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:28,276 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2a5a5383-e8d9-4112-8cab-6d051c919fc8, ghrun-isvtmfzgna.auto.internal:15362): on response callback started
2025-10-16 15:26:28,284 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2a5a5383-e8d9-4112-8cab-6d051c919fc8, ghrun-isvtmfzgna.auto.internal:15362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50001&id=YTM1ZDBlNDktMjlmOWNlODYtNmU3MDZkNWUtMzZiODVmYTE=" } } } }
2025-10-16 15:26:28,295 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,306 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,306 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2a5a5383-e8d9-4112-8cab-6d051c919fc8, ghrun-isvtmfzgna.auto.internal:15362): on response callback success
2025-10-16 15:26:28,307 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 58d47fd3-3335-47e1-ab10-a71d945edc2e, ghrun-isvtmfzgna.auto.internal:15362): creating call state
2025-10-16 15:26:28,308 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 58d47fd3-3335-47e1-ab10-a71d945edc2e, ghrun-isvtmfzgna.auto.internal:15362): request = { session_id: "ydb://session/3?node_id=50001&id=YTM1ZDBlNDktMjlmOWNlODYtNmU3MDZkNWUtMzZiODVmYTE=" path: "/Root/users/database_1/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:28,398 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 58d47fd3-3335-47e1-ab10-a71d945edc2e, ghrun-isvtmfzgna.auto.internal:15362): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:28,398 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,413 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 6f00b23b-6ca5-4565-8f99-aed241a45b00, ghrun-isvtmfzgna.auto.internal:15362): creating call state
2025-10-16 15:26:28,417 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 6f00b23b-6ca5-4565-8f99-aed241a45b00, ghrun-isvtmfzgna.auto.internal:15362): request = { session_id: "ydb://session/3?node_id=50001&id=YTM1ZDBlNDktMjlmOWNlODYtNmU3MDZkNWUtMzZiODVmYTE=" path: "/Root/users/database_1/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:28,517 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 6f00b23b-6ca5-4565-8f99-aed241a45b00, ghrun-isvtmfzgna.auto.internal:15362): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:28,517 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,529 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 09fad5af-c997-49cf-9fd1-c6f46d58bb6c, ghrun-isvtmfzgna.auto.internal:15362): creating call state
2025-10-16 15:26:28,537 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 09fad5af-c997-49cf-9fd1-c6f46d58bb6c, ghrun-isvtmfzgna.auto.internal:15362): request = { session_id: "ydb://session/3?node_id=50001&id=YTM1ZDBlNDktMjlmOWNlODYtNmU3MDZkNWUtMzZiODVmYTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_1\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_1\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:28,616 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 09fad5af-c997-49cf-9fd1-c6f46d58bb6c, ghrun-isvtmfzgna.auto.internal:15362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-16 15:26:28,616 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,624 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, bbe310a3-5f50-418c-8c97-685f1c5072bc, ghrun-isvtmfzgna.auto.internal:15362): creating call state
2025-10-16 15:26:28,625 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, bbe310a3-5f50-418c-8c97-685f1c5072bc, ghrun-isvtmfzgna.auto.internal:15362): request = { session_id: "ydb://session/3?node_id=50001&id=YTM1ZDBlNDktMjlmOWNlODYtNmU3MDZkNWUtMzZiODVmYTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:28,733 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, bbe310a3-5f50-418c-8c97-685f1c5072bc, ghrun-isvtmfzgna.auto.internal:15362): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_1" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-16 15:26:28,734 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,734 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 66591947-7e50-49de-bb0a-f532dfb1a55e, ghrun-isvtmfzgna.auto.internal:15362): creating call state
2025-10-16 15:26:28,755 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 66591947-7e50-49de-bb0a-f532dfb1a55e, ghrun-isvtmfzgna.auto.internal:15362): request = { session_id: "ydb://session/3?node_id=50001&id=YTM1ZDBlNDktMjlmOWNlODYtNmU3MDZkNWUtMzZiODVmYTE=" path: "/Root/users/database_1/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:28,800 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 66591947-7e50-49de-bb0a-f532dfb1a55e, ghrun-isvtmfzgna.auto.internal:15362): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:28,800 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,800 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-16 15:26:28,800 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-16 15:26:28,801 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-16 15:26:28,801 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4881f05d70>, reason: session-pool-terminated
2025-10-16 15:26:28,803 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4881f05d70> is no longer active. Current active count 0.
2025-10-16 15:26:28,803 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 73866cfc-03ba-4b8f-b8fe-adf8ccb5d9b0, ghrun-isvtmfzgna.auto.internal:15362): creating call state
2025-10-16 15:26:28,804 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 73866cfc-03ba-4b8f-b8fe-adf8ccb5d9b0, ghrun-isvtmfzgna.auto.internal:15362): request = { session_id: "ydb://session/3?node_id=50001&id=YTM1ZDBlNDktMjlmOWNlODYtNmU3MDZkNWUtMzZiODVmYTE=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:28,807 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-16 15:26:28,822 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 73866cfc-03ba-4b8f-b8fe-adf8ccb5d9b0, ghrun-isvtmfzgna.auto.internal:15362): on response callback started
2025-10-16 15:26:28,825 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 73866cfc-03ba-4b8f-b8fe-adf8ccb5d9b0, ghrun-isvtmfzgna.auto.internal:15362): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:28,826 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 73866cfc-03ba-4b8f-b8fe-adf8ccb5d9b0, ghrun-isvtmfzgna.auto.internal:15362): on response callback success
2025-10-16 15:26:28,830 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4881f05d70>
2025-10-16 15:26:28,855 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-16 15:26:28,855 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 15:26:28,856 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 15:26:28,856 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:28,857 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-isvtmfzgna.auto.internal:15362
2025-10-16 15:26:28,857 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 15:26:28,867 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_1
2025-10-16 15:26:28,875 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_1: send console operation, token None
2025-10-16 15:26:28,919 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_1: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_1&cmstid=72057594037936131&txid=1760628388915180&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-16 15:26:28,985 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_1: wait console operation done
2025-10-16 15:26:29,515 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_1: console operation done
2025-10-16 15:26:29,516 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_1: wait tenant gone
2025-10-16 15:26:29,541 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_1: tenant gone
2025-10-16 15:26:32,544 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:15362/2
2025-10-16 15:26:33,109 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:33,110 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7311
2025-10-16 15:26:33,110 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8520
2025-10-16 15:26:33,110 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3290
2025-10-16 15:26:33,111 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12924
2025-10-16 15:26:33,113 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30294
2025-10-16 15:26:33,114 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:33,114 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_2 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_qu9pjvax.log --grpc-port=7311 --mon-port=8520 --ic-port=3290
2025-10-16 15:26:33,115 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:33,115 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_2 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_qu9pjvax.log --grpc-port=7311 --mon-port=8520 --ic-port=3290
2025-10-16 15:26:33,116 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:61697', '--tenant=/Root/users/database_2', '--log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3/logfile_qu9pjvax.log', '--grpc-port=7311', '--mon-port=8520', '--ic-port=3290')' in '/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_3' (no wait)
2025-10-16 15:26:33,128 - DEBUG - ya.test - execute: Command pid: 4131335
2025-10-16 15:26:33,130 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:7311/3
2025-10-16 15:26:33,132 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-10-16 15:26:33,133 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-10-16 15:26:33,137 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 15:26:33,142 - DEBUG - ya.test - execute: Command pid: 4131343
2025-10-16 15:26:34,211 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) rc: 0
2025-10-16 15:26:34,211 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) elapsed time (sec): 1.0679163932800293
2025-10-16 15:26:34,212 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) idrss: 0
2025-10-16 15:26:34,212 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) inblock: 0
2025-10-16 15:26:34,212 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) isrss: 0
2025-10-16 15:26:34,213 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) ixrss: 0
2025-10-16 15:26:34,214 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) majflt: 0
2025-10-16 15:26:34,214 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) maxrss: 543052
2025-10-16 15:26:34,214 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) minflt: 16231
2025-10-16 15:26:34,215 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) msgrcv: 0
2025-10-16 15:26:34,215 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) msgsnd: 0
2025-10-16 15:26:34,215 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) nivcsw: 206
2025-10-16 15:26:34,215 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) nsignals: 0
2025-10-16 15:26:34,215 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) nswap: 0
2025-10-16 15:26:34,215 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) nvcsw: 42
2025-10-16 15:26:34,217 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) oublock: 8
2025-10-16 15:26:34,217 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) stime: 0.414166
2025-10-16 15:26:34,218 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) utime: 0.130246
2025-10-16 15:26:34,218 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) wtime: 1.072
2025-10-16 15:26:34,218 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) output:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-isvtmfzgna
    Host information: 
        Linux ghrun-isvtmfzgna 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-16 15:26:34,218 - DEBUG - ya.test - _save_outputs: Command (pid 4131343) errors:

2025-10-16 15:26:34,219 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 3 version:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7
2025-10-16 15:26:36,238 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:36,240 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 15:26:36,241 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:36,246 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-16 15:26:36,247 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4881efe860 state=pending>
2025-10-16 15:26:36,247 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-16 15:26:36,247 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,248 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,248 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-16 15:26:36,271 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_2
2025-10-16 15:26:36,275 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 604054b5-fa31-450d-b1c1-6681b103f332, localhost:61697): creating call state
2025-10-16 15:26:36,275 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 604054b5-fa31-450d-b1c1-6681b103f332, localhost:61697): request = { database: "/Root/users/database_2" }
2025-10-16 15:26:36,285 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 604054b5-fa31-450d-b1c1-6681b103f332, localhost:61697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-isvtmfzgna.auto.internal" port: 7311 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50002 } self_location: "1" } } } }
2025-10-16 15:26:36,288 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_2: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-isvtmfzgna.auto.internal:7311, location , ssl: False>]>
2025-10-16 15:26:36,295 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:36,312 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:36,312 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,313 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ef17b1d8-c285-4342-8732-cf45809b8f7b, ghrun-isvtmfzgna.auto.internal:7311): creating call state
2025-10-16 15:26:36,313 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ef17b1d8-c285-4342-8732-cf45809b8f7b, ghrun-isvtmfzgna.auto.internal:7311): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:36,323 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ef17b1d8-c285-4342-8732-cf45809b8f7b, ghrun-isvtmfzgna.auto.internal:7311): on response callback started
2025-10-16 15:26:36,324 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ef17b1d8-c285-4342-8732-cf45809b8f7b, ghrun-isvtmfzgna.auto.internal:7311): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50002&id=OTMwMWFkMjEtOTVjZmIwY2QtYzIzMTliYTMtYTQ0YTAyYWQ=" } } } }
2025-10-16 15:26:36,324 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ef17b1d8-c285-4342-8732-cf45809b8f7b, ghrun-isvtmfzgna.auto.internal:7311): on response callback success
2025-10-16 15:26:36,325 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,325 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,326 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 6750f086-de93-4644-8594-fa8e4ff9e162, ghrun-isvtmfzgna.auto.internal:7311): creating call state
2025-10-16 15:26:36,328 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 6750f086-de93-4644-8594-fa8e4ff9e162, ghrun-isvtmfzgna.auto.internal:7311): request = { session_id: "ydb://session/3?node_id=50002&id=OTMwMWFkMjEtOTVjZmIwY2QtYzIzMTliYTMtYTQ0YTAyYWQ=" path: "/Root/users/database_2/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:36,573 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 6750f086-de93-4644-8594-fa8e4ff9e162, ghrun-isvtmfzgna.auto.internal:7311): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:36,573 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,574 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 77f5ddf4-b7a8-4953-ac2a-c52b8c5d0352, ghrun-isvtmfzgna.auto.internal:7311): creating call state
2025-10-16 15:26:36,592 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 77f5ddf4-b7a8-4953-ac2a-c52b8c5d0352, ghrun-isvtmfzgna.auto.internal:7311): request = { session_id: "ydb://session/3?node_id=50002&id=OTMwMWFkMjEtOTVjZmIwY2QtYzIzMTliYTMtYTQ0YTAyYWQ=" path: "/Root/users/database_2/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:36,665 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 77f5ddf4-b7a8-4953-ac2a-c52b8c5d0352, ghrun-isvtmfzgna.auto.internal:7311): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:36,666 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,667 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4432e03e-fe83-49e5-8eae-cf4068c4ae1e, ghrun-isvtmfzgna.auto.internal:7311): creating call state
2025-10-16 15:26:36,668 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4432e03e-fe83-49e5-8eae-cf4068c4ae1e, ghrun-isvtmfzgna.auto.internal:7311): request = { session_id: "ydb://session/3?node_id=50002&id=OTMwMWFkMjEtOTVjZmIwY2QtYzIzMTliYTMtYTQ0YTAyYWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_2\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_2\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:36,716 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4432e03e-fe83-49e5-8eae-cf4068c4ae1e, ghrun-isvtmfzgna.auto.internal:7311): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-16 15:26:36,716 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,717 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 67feff89-7ff7-4713-969e-8af1e386dcc5, ghrun-isvtmfzgna.auto.internal:7311): creating call state
2025-10-16 15:26:36,718 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 67feff89-7ff7-4713-969e-8af1e386dcc5, ghrun-isvtmfzgna.auto.internal:7311): request = { session_id: "ydb://session/3?node_id=50002&id=OTMwMWFkMjEtOTVjZmIwY2QtYzIzMTliYTMtYTQ0YTAyYWQ=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:36,775 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 67feff89-7ff7-4713-969e-8af1e386dcc5, ghrun-isvtmfzgna.auto.internal:7311): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_2" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-16 15:26:36,776 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,777 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, d7314b6c-6655-4797-a338-70ab13366e35, ghrun-isvtmfzgna.auto.internal:7311): creating call state
2025-10-16 15:26:36,777 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, d7314b6c-6655-4797-a338-70ab13366e35, ghrun-isvtmfzgna.auto.internal:7311): request = { session_id: "ydb://session/3?node_id=50002&id=OTMwMWFkMjEtOTVjZmIwY2QtYzIzMTliYTMtYTQ0YTAyYWQ=" path: "/Root/users/database_2/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:36,818 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, d7314b6c-6655-4797-a338-70ab13366e35, ghrun-isvtmfzgna.auto.internal:7311): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:36,819 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,819 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-16 15:26:36,820 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-16 15:26:36,820 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-16 15:26:36,820 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4881efe5e0>, reason: session-pool-terminated
2025-10-16 15:26:36,820 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4881efe5e0> is no longer active. Current active count 0.
2025-10-16 15:26:36,821 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1069f99f-0ddf-4c49-9e08-4ddb922ac848, ghrun-isvtmfzgna.auto.internal:7311): creating call state
2025-10-16 15:26:36,821 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1069f99f-0ddf-4c49-9e08-4ddb922ac848, ghrun-isvtmfzgna.auto.internal:7311): request = { session_id: "ydb://session/3?node_id=50002&id=OTMwMWFkMjEtOTVjZmIwY2QtYzIzMTliYTMtYTQ0YTAyYWQ=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:36,821 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-16 15:26:36,835 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1069f99f-0ddf-4c49-9e08-4ddb922ac848, ghrun-isvtmfzgna.auto.internal:7311): on response callback started
2025-10-16 15:26:36,840 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1069f99f-0ddf-4c49-9e08-4ddb922ac848, ghrun-isvtmfzgna.auto.internal:7311): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:36,857 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 1069f99f-0ddf-4c49-9e08-4ddb922ac848, ghrun-isvtmfzgna.auto.internal:7311): on response callback success
2025-10-16 15:26:36,858 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4881efe5e0>
2025-10-16 15:26:36,872 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-16 15:26:36,874 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 15:26:36,874 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 15:26:36,874 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:36,875 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-isvtmfzgna.auto.internal:7311
2025-10-16 15:26:36,876 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 15:26:36,884 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_2
2025-10-16 15:26:36,903 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_2: send console operation, token None
2025-10-16 15:26:36,921 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_2: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_2&cmstid=72057594037936131&txid=1760628396907470&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-16 15:26:36,922 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_2: wait console operation done
2025-10-16 15:26:37,427 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_2: console operation done
2025-10-16 15:26:37,428 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_2: wait tenant gone
2025-10-16 15:26:37,457 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_2: tenant gone
2025-10-16 15:26:40,463 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:7311/3
2025-10-16 15:26:41,001 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:41,002 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11545
2025-10-16 15:26:41,012 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7223
2025-10-16 15:26:41,049 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14534
2025-10-16 15:26:41,054 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14535
2025-10-16 15:26:41,055 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15344
2025-10-16 15:26:41,055 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5467
2025-10-16 15:26:41,056 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:41,056 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_3 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_m63i9tu1.log --grpc-port=11545 --mon-port=7223 --ic-port=14535
2025-10-16 15:26:41,061 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:41,088 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_3 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_m63i9tu1.log --grpc-port=11545 --mon-port=7223 --ic-port=14535
2025-10-16 15:26:41,108 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:61697', '--tenant=/Root/users/database_3', '--log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4/logfile_m63i9tu1.log', '--grpc-port=11545', '--mon-port=7223', '--ic-port=14535')' in '/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_4' (no wait)
2025-10-16 15:26:41,121 - DEBUG - ya.test - execute: Command pid: 4134978
2025-10-16 15:26:41,122 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:11545/4
2025-10-16 15:26:41,160 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.5
2025-10-16 15:26:41,171 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.5
2025-10-16 15:26:41,190 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 15:26:41,213 - DEBUG - ya.test - execute: Command pid: 4134984
2025-10-16 15:26:43,272 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) rc: 0
2025-10-16 15:26:43,278 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) elapsed time (sec): 2.0587146282196045
2025-10-16 15:26:43,278 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) idrss: 0
2025-10-16 15:26:43,278 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) inblock: 0
2025-10-16 15:26:43,278 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) isrss: 0
2025-10-16 15:26:43,278 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) ixrss: 0
2025-10-16 15:26:43,278 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) majflt: 0
2025-10-16 15:26:43,279 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) maxrss: 543052
2025-10-16 15:26:43,279 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) minflt: 16226
2025-10-16 15:26:43,279 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) msgrcv: 0
2025-10-16 15:26:43,279 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) msgsnd: 0
2025-10-16 15:26:43,279 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) nivcsw: 399
2025-10-16 15:26:43,279 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) nsignals: 0
2025-10-16 15:26:43,280 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) nswap: 0
2025-10-16 15:26:43,280 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) nvcsw: 39
2025-10-16 15:26:43,280 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) oublock: 8
2025-10-16 15:26:43,280 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) stime: 0.8248219999999999
2025-10-16 15:26:43,280 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) utime: 0.130517
2025-10-16 15:26:43,280 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) wtime: 2.066
2025-10-16 15:26:43,281 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) output:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-isvtmfzgna
    Host information: 
        Linux ghrun-isvtmfzgna 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-16 15:26:43,281 - DEBUG - ya.test - _save_outputs: Command (pid 4134984) errors:

2025-10-16 15:26:43,281 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 4 version:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7
2025-10-16 15:26:45,313 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:45,323 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 15:26:45,323 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:45,342 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-16 15:26:45,342 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4883f63440 state=pending>
2025-10-16 15:26:45,342 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-16 15:26:45,343 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,343 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,343 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-16 15:26:45,345 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_3
2025-10-16 15:26:45,354 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 2b61c14c-84fb-4b14-9a1e-843fbaef4589, localhost:61697): creating call state
2025-10-16 15:26:45,355 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 2b61c14c-84fb-4b14-9a1e-843fbaef4589, localhost:61697): request = { database: "/Root/users/database_3" }
2025-10-16 15:26:45,358 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 2b61c14c-84fb-4b14-9a1e-843fbaef4589, localhost:61697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-isvtmfzgna.auto.internal" port: 11545 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50003 } self_location: "1" } } } }
2025-10-16 15:26:45,359 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_3: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-isvtmfzgna.auto.internal:11545, location , ssl: False>]>
2025-10-16 15:26:45,359 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:45,390 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:45,391 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,392 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 505b26cb-5dc3-424c-b010-9ffa976abbaa, ghrun-isvtmfzgna.auto.internal:11545): creating call state
2025-10-16 15:26:45,392 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 505b26cb-5dc3-424c-b010-9ffa976abbaa, ghrun-isvtmfzgna.auto.internal:11545): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:45,397 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 505b26cb-5dc3-424c-b010-9ffa976abbaa, ghrun-isvtmfzgna.auto.internal:11545): on response callback started
2025-10-16 15:26:45,414 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 505b26cb-5dc3-424c-b010-9ffa976abbaa, ghrun-isvtmfzgna.auto.internal:11545): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50003&id=ZDY4NzQ3YTgtYjUzYTM5MWYtYjJhMzg1YjUtNTc5ODk4YmY=" } } } }
2025-10-16 15:26:45,414 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,415 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,415 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 505b26cb-5dc3-424c-b010-9ffa976abbaa, ghrun-isvtmfzgna.auto.internal:11545): on response callback success
2025-10-16 15:26:45,416 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 742da770-5c80-461a-9908-86886aea9302, ghrun-isvtmfzgna.auto.internal:11545): creating call state
2025-10-16 15:26:45,419 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 742da770-5c80-461a-9908-86886aea9302, ghrun-isvtmfzgna.auto.internal:11545): request = { session_id: "ydb://session/3?node_id=50003&id=ZDY4NzQ3YTgtYjUzYTM5MWYtYjJhMzg1YjUtNTc5ODk4YmY=" path: "/Root/users/database_3/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:45,566 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 742da770-5c80-461a-9908-86886aea9302, ghrun-isvtmfzgna.auto.internal:11545): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:45,569 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,570 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 595a8a6b-d15c-4ea2-a65c-66b796db1533, ghrun-isvtmfzgna.auto.internal:11545): creating call state
2025-10-16 15:26:45,611 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 595a8a6b-d15c-4ea2-a65c-66b796db1533, ghrun-isvtmfzgna.auto.internal:11545): request = { session_id: "ydb://session/3?node_id=50003&id=ZDY4NzQ3YTgtYjUzYTM5MWYtYjJhMzg1YjUtNTc5ODk4YmY=" path: "/Root/users/database_3/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:45,707 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 595a8a6b-d15c-4ea2-a65c-66b796db1533, ghrun-isvtmfzgna.auto.internal:11545): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:45,724 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,744 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 561b178b-23c7-4219-b5f1-43ab2ac98764, ghrun-isvtmfzgna.auto.internal:11545): creating call state
2025-10-16 15:26:45,745 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 561b178b-23c7-4219-b5f1-43ab2ac98764, ghrun-isvtmfzgna.auto.internal:11545): request = { session_id: "ydb://session/3?node_id=50003&id=ZDY4NzQ3YTgtYjUzYTM5MWYtYjJhMzg1YjUtNTc5ODk4YmY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_3\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_3\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:45,869 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 561b178b-23c7-4219-b5f1-43ab2ac98764, ghrun-isvtmfzgna.auto.internal:11545): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-16 15:26:45,896 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,903 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 395904ab-c917-474e-b13c-0cee90e41a24, ghrun-isvtmfzgna.auto.internal:11545): creating call state
2025-10-16 15:26:45,905 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 395904ab-c917-474e-b13c-0cee90e41a24, ghrun-isvtmfzgna.auto.internal:11545): request = { session_id: "ydb://session/3?node_id=50003&id=ZDY4NzQ3YTgtYjUzYTM5MWYtYjJhMzg1YjUtNTc5ODk4YmY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:45,965 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 395904ab-c917-474e-b13c-0cee90e41a24, ghrun-isvtmfzgna.auto.internal:11545): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_3" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-16 15:26:45,984 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:45,985 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 04cd92ed-a6ac-4ab0-99c4-33a758cf3cd2, ghrun-isvtmfzgna.auto.internal:11545): creating call state
2025-10-16 15:26:45,986 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 04cd92ed-a6ac-4ab0-99c4-33a758cf3cd2, ghrun-isvtmfzgna.auto.internal:11545): request = { session_id: "ydb://session/3?node_id=50003&id=ZDY4NzQ3YTgtYjUzYTM5MWYtYjJhMzg1YjUtNTc5ODk4YmY=" path: "/Root/users/database_3/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:46,032 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 04cd92ed-a6ac-4ab0-99c4-33a758cf3cd2, ghrun-isvtmfzgna.auto.internal:11545): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:46,034 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:46,035 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-16 15:26:46,036 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-16 15:26:46,036 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-16 15:26:46,036 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-16 15:26:46,049 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4883f63df0>, reason: session-pool-terminated
2025-10-16 15:26:46,057 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4883f63df0> is no longer active. Current active count 0.
2025-10-16 15:26:46,057 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, cadc2e01-c485-4036-b1a7-d60696267ff3, ghrun-isvtmfzgna.auto.internal:11545): creating call state
2025-10-16 15:26:46,058 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, cadc2e01-c485-4036-b1a7-d60696267ff3, ghrun-isvtmfzgna.auto.internal:11545): request = { session_id: "ydb://session/3?node_id=50003&id=ZDY4NzQ3YTgtYjUzYTM5MWYtYjJhMzg1YjUtNTc5ODk4YmY=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:46,059 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4883f63df0>
2025-10-16 15:26:46,060 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-16 15:26:46,063 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 15:26:46,063 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 15:26:46,063 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:46,064 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-isvtmfzgna.auto.internal:11545
2025-10-16 15:26:46,064 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 15:26:46,060 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cadc2e01-c485-4036-b1a7-d60696267ff3, ghrun-isvtmfzgna.auto.internal:11545): on response callback started
2025-10-16 15:26:46,066 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, cadc2e01-c485-4036-b1a7-d60696267ff3, ghrun-isvtmfzgna.auto.internal:11545): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:46,066 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, cadc2e01-c485-4036-b1a7-d60696267ff3, ghrun-isvtmfzgna.auto.internal:11545): on response callback success
2025-10-16 15:26:46,068 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_3
2025-10-16 15:26:46,068 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_3: send console operation, token None
2025-10-16 15:26:46,078 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_3: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_3&cmstid=72057594037936131&txid=1760628406073269&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-16 15:26:46,079 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_3: wait console operation done
2025-10-16 15:26:46,592 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_3: console operation done
2025-10-16 15:26:46,595 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_3: wait tenant gone
2025-10-16 15:26:46,604 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_3: tenant gone
2025-10-16 15:26:49,618 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11545/4
2025-10-16 15:26:50,138 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:50,138 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10196
2025-10-16 15:26:50,139 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18084
2025-10-16 15:26:50,139 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14989
2025-10-16 15:26:50,140 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24039
2025-10-16 15:26:50,140 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13371
2025-10-16 15:26:50,141 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:50,141 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_4 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_iywf22sg.log --grpc-port=10196 --mon-port=18084 --ic-port=14989
2025-10-16 15:26:50,141 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:50,142 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_4 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_iywf22sg.log --grpc-port=10196 --mon-port=18084 --ic-port=14989
2025-10-16 15:26:50,144 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:61697', '--tenant=/Root/users/database_4', '--log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5/logfile_iywf22sg.log', '--grpc-port=10196', '--mon-port=18084', '--ic-port=14989')' in '/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_5' (no wait)
2025-10-16 15:26:50,145 - DEBUG - ya.test - execute: Command pid: 4138776
2025-10-16 15:26:50,146 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10196/5
2025-10-16 15:26:50,148 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.6
2025-10-16 15:26:50,149 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.6
2025-10-16 15:26:50,149 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 15:26:50,150 - DEBUG - ya.test - execute: Command pid: 4138778
2025-10-16 15:26:50,974 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) rc: 0
2025-10-16 15:26:50,974 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) elapsed time (sec): 0.8227519989013672
2025-10-16 15:26:50,974 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) idrss: 0
2025-10-16 15:26:50,974 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) inblock: 0
2025-10-16 15:26:50,975 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) isrss: 0
2025-10-16 15:26:50,975 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) ixrss: 0
2025-10-16 15:26:50,975 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) majflt: 0
2025-10-16 15:26:50,995 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) maxrss: 543052
2025-10-16 15:26:50,995 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) minflt: 16226
2025-10-16 15:26:51,008 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) msgrcv: 0
2025-10-16 15:26:51,008 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) msgsnd: 0
2025-10-16 15:26:51,008 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) nivcsw: 174
2025-10-16 15:26:51,008 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) nsignals: 0
2025-10-16 15:26:51,025 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) nswap: 0
2025-10-16 15:26:51,027 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) nvcsw: 15
2025-10-16 15:26:51,029 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) oublock: 8
2025-10-16 15:26:51,030 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) stime: 0.33364499999999997
2025-10-16 15:26:51,030 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) utime: 0.12290899999999999
2025-10-16 15:26:51,030 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) wtime: 0.824
2025-10-16 15:26:51,031 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) output:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-isvtmfzgna
    Host information: 
        Linux ghrun-isvtmfzgna 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-16 15:26:51,031 - DEBUG - ya.test - _save_outputs: Command (pid 4138778) errors:

2025-10-16 15:26:51,031 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 5 version:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7
2025-10-16 15:26:53,042 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:53,075 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 15:26:53,076 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:53,080 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-16 15:26:53,080 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4881eeb3f0 state=pending>
2025-10-16 15:26:53,082 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-16 15:26:53,082 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,082 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,083 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-16 15:26:53,104 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_4
2025-10-16 15:26:53,109 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6d0ae696-046f-4e13-b1e6-4bd178eef9fa, localhost:61697): creating call state
2025-10-16 15:26:53,110 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6d0ae696-046f-4e13-b1e6-4bd178eef9fa, localhost:61697): request = { database: "/Root/users/database_4" }
2025-10-16 15:26:53,114 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6d0ae696-046f-4e13-b1e6-4bd178eef9fa, localhost:61697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-isvtmfzgna.auto.internal" port: 10196 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50004 } self_location: "1" } } } }
2025-10-16 15:26:53,115 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_4: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-isvtmfzgna.auto.internal:10196, location , ssl: False>]>
2025-10-16 15:26:53,116 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:26:53,132 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:53,133 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,135 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cec119fa-5b85-401e-966f-fa29c3464172, ghrun-isvtmfzgna.auto.internal:10196): creating call state
2025-10-16 15:26:53,139 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cec119fa-5b85-401e-966f-fa29c3464172, ghrun-isvtmfzgna.auto.internal:10196): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:53,154 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cec119fa-5b85-401e-966f-fa29c3464172, ghrun-isvtmfzgna.auto.internal:10196): on response callback started
2025-10-16 15:26:53,157 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cec119fa-5b85-401e-966f-fa29c3464172, ghrun-isvtmfzgna.auto.internal:10196): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50004&id=YmU3NDczODYtODg4NGI0YzctOWZlNWEzM2MtNzcwZTQ1YWI=" } } } }
2025-10-16 15:26:53,166 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,166 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,166 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, cec119fa-5b85-401e-966f-fa29c3464172, ghrun-isvtmfzgna.auto.internal:10196): on response callback success
2025-10-16 15:26:53,168 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, f3de75de-060f-4b1c-b2dc-ea628821e15a, ghrun-isvtmfzgna.auto.internal:10196): creating call state
2025-10-16 15:26:53,171 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, f3de75de-060f-4b1c-b2dc-ea628821e15a, ghrun-isvtmfzgna.auto.internal:10196): request = { session_id: "ydb://session/3?node_id=50004&id=YmU3NDczODYtODg4NGI0YzctOWZlNWEzM2MtNzcwZTQ1YWI=" path: "/Root/users/database_4/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:53,268 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, f3de75de-060f-4b1c-b2dc-ea628821e15a, ghrun-isvtmfzgna.auto.internal:10196): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:53,268 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,269 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, ecc26e5f-0e0a-429c-84c3-16feed7c5f73, ghrun-isvtmfzgna.auto.internal:10196): creating call state
2025-10-16 15:26:53,270 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, ecc26e5f-0e0a-429c-84c3-16feed7c5f73, ghrun-isvtmfzgna.auto.internal:10196): request = { session_id: "ydb://session/3?node_id=50004&id=YmU3NDczODYtODg4NGI0YzctOWZlNWEzM2MtNzcwZTQ1YWI=" path: "/Root/users/database_4/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:53,408 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, ecc26e5f-0e0a-429c-84c3-16feed7c5f73, ghrun-isvtmfzgna.auto.internal:10196): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:53,408 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,409 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 0d683118-a1b3-4e59-8fc1-0e0984d0e319, ghrun-isvtmfzgna.auto.internal:10196): creating call state
2025-10-16 15:26:53,409 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 0d683118-a1b3-4e59-8fc1-0e0984d0e319, ghrun-isvtmfzgna.auto.internal:10196): request = { session_id: "ydb://session/3?node_id=50004&id=YmU3NDczODYtODg4NGI0YzctOWZlNWEzM2MtNzcwZTQ1YWI=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_4\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_4\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:53,449 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 0d683118-a1b3-4e59-8fc1-0e0984d0e319, ghrun-isvtmfzgna.auto.internal:10196): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-16 15:26:53,451 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,452 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 4ef22560-97a5-45dc-85cd-3eb0b214695c, ghrun-isvtmfzgna.auto.internal:10196): creating call state
2025-10-16 15:26:53,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 4ef22560-97a5-45dc-85cd-3eb0b214695c, ghrun-isvtmfzgna.auto.internal:10196): request = { session_id: "ydb://session/3?node_id=50004&id=YmU3NDczODYtODg4NGI0YzctOWZlNWEzM2MtNzcwZTQ1YWI=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:53,500 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 4ef22560-97a5-45dc-85cd-3eb0b214695c, ghrun-isvtmfzgna.auto.internal:10196): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_4" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_4" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-16 15:26:53,501 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,502 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, de7c7752-c291-4872-9a75-00d6ed8362a5, ghrun-isvtmfzgna.auto.internal:10196): creating call state
2025-10-16 15:26:53,502 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, de7c7752-c291-4872-9a75-00d6ed8362a5, ghrun-isvtmfzgna.auto.internal:10196): request = { session_id: "ydb://session/3?node_id=50004&id=YmU3NDczODYtODg4NGI0YzctOWZlNWEzM2MtNzcwZTQ1YWI=" path: "/Root/users/database_4/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:26:53,561 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, de7c7752-c291-4872-9a75-00d6ed8362a5, ghrun-isvtmfzgna.auto.internal:10196): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:53,562 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,562 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-16 15:26:53,563 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-16 15:26:53,565 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-16 15:26:53,565 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4881eebcb0>, reason: session-pool-terminated
2025-10-16 15:26:53,566 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4881eebcb0> is no longer active. Current active count 0.
2025-10-16 15:26:53,566 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 04792aa0-c4c7-40d3-98df-745a4ebd11b7, ghrun-isvtmfzgna.auto.internal:10196): creating call state
2025-10-16 15:26:53,566 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 04792aa0-c4c7-40d3-98df-745a4ebd11b7, ghrun-isvtmfzgna.auto.internal:10196): request = { session_id: "ydb://session/3?node_id=50004&id=YmU3NDczODYtODg4NGI0YzctOWZlNWEzM2MtNzcwZTQ1YWI=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:26:53,563 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-16 15:26:53,573 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 04792aa0-c4c7-40d3-98df-745a4ebd11b7, ghrun-isvtmfzgna.auto.internal:10196): on response callback started
2025-10-16 15:26:53,574 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 04792aa0-c4c7-40d3-98df-745a4ebd11b7, ghrun-isvtmfzgna.auto.internal:10196): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:26:53,595 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 04792aa0-c4c7-40d3-98df-745a4ebd11b7, ghrun-isvtmfzgna.auto.internal:10196): on response callback success
2025-10-16 15:26:53,596 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4881eebcb0>
2025-10-16 15:26:53,596 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-16 15:26:53,596 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 15:26:53,597 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 15:26:53,597 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:26:53,598 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-isvtmfzgna.auto.internal:10196
2025-10-16 15:26:53,600 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 15:26:53,602 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_4
2025-10-16 15:26:53,602 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_4: send console operation, token None
2025-10-16 15:26:53,623 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_4: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_4&cmstid=72057594037936131&txid=1760628413606822&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-16 15:26:53,623 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_4: wait console operation done
2025-10-16 15:26:54,128 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_4: console operation done
2025-10-16 15:26:54,129 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_4: wait tenant gone
2025-10-16 15:26:54,132 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_4: tenant gone
2025-10-16 15:26:57,148 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10196/5
2025-10-16 15:26:57,668 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd
2025-10-16 15:26:57,672 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2034
2025-10-16 15:26:57,673 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17293
2025-10-16 15:26:57,673 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31294
2025-10-16 15:26:57,674 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17221
2025-10-16 15:26:57,674 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31260
2025-10-16 15:26:57,676 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:57,676 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_5 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6/logfile_flcnfzyt.log --grpc-port=2034 --mon-port=17293 --ic-port=31294
2025-10-16 15:26:57,676 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs"
2025-10-16 15:26:57,677 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:61697 --tenant=/Root/users/database_5 --log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6/logfile_flcnfzyt.log --grpc-port=2034 --mon-port=17293 --ic-port=31294
2025-10-16 15:26:57,679 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:61697', '--tenant=/Root/users/database_5', '--log-file-name=/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6/logfile_flcnfzyt.log', '--grpc-port=2034', '--mon-port=17293', '--ic-port=31294')' in '/home/runner/.ya/build/build_root/bn3t/000282/ydb/tests/functional/tenants/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_dynamic_tenants.py.test_create_and_drop_tenants.enable_alter_database_create_hive_first--true/cluster/slot_6' (no wait)
2025-10-16 15:26:57,692 - DEBUG - ya.test - execute: Command pid: 4144524
2025-10-16 15:26:57,693 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:2034/6
2025-10-16 15:26:57,695 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.7
2025-10-16 15:26:57,699 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.7
2025-10-16 15:26:57,699 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bn3t/000282/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 15:26:57,702 - DEBUG - ya.test - execute: Command pid: 4144548
2025-10-16 15:27:00,707 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) rc: 0
2025-10-16 15:27:00,707 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) elapsed time (sec): 3.004051685333252
2025-10-16 15:27:00,708 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) idrss: 0
2025-10-16 15:27:00,708 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) inblock: 0
2025-10-16 15:27:00,708 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) isrss: 0
2025-10-16 15:27:00,708 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) ixrss: 0
2025-10-16 15:27:00,708 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) majflt: 0
2025-10-16 15:27:00,708 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) maxrss: 543052
2025-10-16 15:27:00,709 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) minflt: 16226
2025-10-16 15:27:00,709 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) msgrcv: 0
2025-10-16 15:27:00,709 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) msgsnd: 0
2025-10-16 15:27:00,709 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) nivcsw: 327
2025-10-16 15:27:00,709 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) nsignals: 0
2025-10-16 15:27:00,710 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) nswap: 0
2025-10-16 15:27:00,710 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) nvcsw: 35
2025-10-16 15:27:00,710 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) oublock: 8
2025-10-16 15:27:00,710 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) stime: 0.788801
2025-10-16 15:27:00,710 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) utime: 0.151423
2025-10-16 15:27:00,710 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) wtime: 3.007
2025-10-16 15:27:00,711 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) output:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-isvtmfzgna
    Host information: 
        Linux ghrun-isvtmfzgna 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-16 15:27:00,711 - DEBUG - ya.test - _save_outputs: Command (pid 4144548) errors:

2025-10-16 15:27:00,711 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 6 version:
Git info:
    Commit: b0e510ff4fa8fd818fcfc9852802046a2811e1ff
    Branch: 
    Author: Pavel Zuev <pzuev@ydb.tech>
    Summary: Merge 1493b0dee1fe8174a0a867c78951bb0a6aa7e7d8 into 6435c864928e8cf8dc07aef3ecde178385ac05a7
2025-10-16 15:27:02,744 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:27:02,778 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 15:27:02,779 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:27:02,787 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-16 15:27:02,788 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f4883f4f9e0 state=pending>
2025-10-16 15:27:02,788 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2025-10-16 15:27:02,788 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:02,788 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:02,789 - ERROR - ydb.table.SessionPool - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2025-10-16 15:27:02,806 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/users/database_5
2025-10-16 15:27:02,811 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 5b4492d6-39c2-4924-8d92-b6670c82960e, localhost:61697): creating call state
2025-10-16 15:27:02,814 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 5b4492d6-39c2-4924-8d92-b6670c82960e, localhost:61697): request = { database: "/Root/users/database_5" }
2025-10-16 15:27:02,820 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 5b4492d6-39c2-4924-8d92-b6670c82960e, localhost:61697): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-isvtmfzgna.auto.internal" port: 2034 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: 50005 } self_location: "1" } } } }
2025-10-16 15:27:02,821 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/users/database_5: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-isvtmfzgna.auto.internal:2034, location , ssl: False>]>
2025-10-16 15:27:02,821 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 15:27:02,850 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:27:02,851 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:02,852 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ba50e35a-2bb5-456a-b3d3-3222e5c15f92, ghrun-isvtmfzgna.auto.internal:2034): creating call state
2025-10-16 15:27:02,859 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ba50e35a-2bb5-456a-b3d3-3222e5c15f92, ghrun-isvtmfzgna.auto.internal:2034): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:27:02,871 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ba50e35a-2bb5-456a-b3d3-3222e5c15f92, ghrun-isvtmfzgna.auto.internal:2034): on response callback started
2025-10-16 15:27:02,872 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ba50e35a-2bb5-456a-b3d3-3222e5c15f92, ghrun-isvtmfzgna.auto.internal:2034): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50005&id=ZGY1ZGZhNjAtZmM1ZmUwMi04ZGFhZDhlZS01NzVkMzE1OQ==" } } } }
2025-10-16 15:27:02,872 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:02,872 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:02,873 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, ba50e35a-2bb5-456a-b3d3-3222e5c15f92, ghrun-isvtmfzgna.auto.internal:2034): on response callback success
2025-10-16 15:27:02,874 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 3533ca6c-fcd6-4e72-b435-9c42a9bd7519, ghrun-isvtmfzgna.auto.internal:2034): creating call state
2025-10-16 15:27:02,874 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 3533ca6c-fcd6-4e72-b435-9c42a9bd7519, ghrun-isvtmfzgna.auto.internal:2034): request = { session_id: "ydb://session/3?node_id=50005&id=ZGY1ZGZhNjAtZmM1ZmUwMi04ZGFhZDhlZS01NzVkMzE1OQ==" path: "/Root/users/database_5/table" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:27:03,025 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 3533ca6c-fcd6-4e72-b435-9c42a9bd7519, ghrun-isvtmfzgna.auto.internal:2034): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:27:03,027 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:03,028 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 1993e679-75ca-4f67-bc83-404f45ec14d7, ghrun-isvtmfzgna.auto.internal:2034): creating call state
2025-10-16 15:27:03,030 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 1993e679-75ca-4f67-bc83-404f45ec14d7, ghrun-isvtmfzgna.auto.internal:2034): request = { session_id: "ydb://session/3?node_id=50005&id=ZGY1ZGZhNjAtZmM1ZmUwMi04ZGFhZDhlZS01NzVkMzE1OQ==" path: "/Root/users/database_5/table_for_rm" columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:27:03,126 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 1993e679-75ca-4f67-bc83-404f45ec14d7, ghrun-isvtmfzgna.auto.internal:2034): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:27:03,126 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:03,127 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 7b6ef68b-39bd-4b76-9a95-7587182802e2, ghrun-isvtmfzgna.auto.internal:2034): creating call state
2025-10-16 15:27:03,129 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 7b6ef68b-39bd-4b76-9a95-7587182802e2, ghrun-isvtmfzgna.auto.internal:2034): request = { session_id: "ydb://session/3?node_id=50005&id=ZGY1ZGZhNjAtZmM1ZmUwMi04ZGFhZDhlZS01NzVkMzE1OQ==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        upsert into table (id, value)\n                        values (1u, \"/Root/users/database_5\");\n                        upsert into table_for_rm (id, value)\n                        values (2u, \"/Root/users/database_5\");\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:27:03,191 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 7b6ef68b-39bd-4b76-9a95-7587182802e2, ghrun-isvtmfzgna.auto.internal:2034): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-16 15:27:03,192 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:03,195 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 83c9b9fd-a9b3-4924-b7c9-040ee19f8231, ghrun-isvtmfzgna.auto.internal:2034): creating call state
2025-10-16 15:27:03,196 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 83c9b9fd-a9b3-4924-b7c9-040ee19f8231, ghrun-isvtmfzgna.auto.internal:2034): request = { session_id: "ydb://session/3?node_id=50005&id=ZGY1ZGZhNjAtZmM1ZmUwMi04ZGFhZDhlZS01NzVkMzE1OQ==" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "\n                        select id, value FROM table;\n                        select id, value FROM table_for_rm;\n                        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:27:03,278 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 83c9b9fd-a9b3-4924-b7c9-040ee19f8231, ghrun-isvtmfzgna.auto.internal:2034): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 1 } items { text_value: "/Root/users/database_5" } } format: FORMAT_VALUE } result_sets { columns { name: "id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UTF8 } } } } rows { items { uint64_value: 2 } items { text_value: "/Root/users/database_5" } } format: FORMAT_VALUE } tx_meta { } } } } }
2025-10-16 15:27:03,279 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:03,280 - DEBUG - ydb.connection - _prepare_call: RpcState(DropTable, 60c24ce7-0df0-43b2-aeef-9a2f15460582, ghrun-isvtmfzgna.auto.internal:2034): creating call state
2025-10-16 15:27:03,281 - DEBUG - ydb.connection - _log_request: RpcState(DropTable, 60c24ce7-0df0-43b2-aeef-9a2f15460582, ghrun-isvtmfzgna.auto.internal:2034): request = { session_id: "ydb://session/3?node_id=50005&id=ZGY1ZGZhNjAtZmM1ZmUwMi04ZGFhZDhlZS01NzVkMzE1OQ==" path: "/Root/users/database_5/table_for_rm" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-16 15:27:03,330 - DEBUG - ydb.connection - _log_response: RpcState(DropTable, 60c24ce7-0df0-43b2-aeef-9a2f15460582, ghrun-isvtmfzgna.auto.internal:2034): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:27:03,331 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:03,331 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-16 15:27:03,331 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-16 15:27:03,331 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-16 15:27:03,332 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7f4883f4fc60>, reason: session-pool-terminated
2025-10-16 15:27:03,332 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7f4883f4fc60> is no longer active. Current active count 0.
2025-10-16 15:27:03,332 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d739e56b-87eb-4c5a-8648-38dd240496b1, ghrun-isvtmfzgna.auto.internal:2034): creating call state
2025-10-16 15:27:03,333 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d739e56b-87eb-4c5a-8648-38dd240496b1, ghrun-isvtmfzgna.auto.internal:2034): request = { session_id: "ydb://session/3?node_id=50005&id=ZGY1ZGZhNjAtZmM1ZmUwMi04ZGFhZDhlZS01NzVkMzE1OQ==" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-16 15:27:03,334 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-16 15:27:03,357 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d739e56b-87eb-4c5a-8648-38dd240496b1, ghrun-isvtmfzgna.auto.internal:2034): on response callback started
2025-10-16 15:27:03,369 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d739e56b-87eb-4c5a-8648-38dd240496b1, ghrun-isvtmfzgna.auto.internal:2034): response = { operation { ready: true status: SUCCESS } }
2025-10-16 15:27:03,370 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, d739e56b-87eb-4c5a-8648-38dd240496b1, ghrun-isvtmfzgna.auto.internal:2034): on response callback success
2025-10-16 15:27:03,370 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7f4883f4fc60>
2025-10-16 15:27:03,370 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-16 15:27:03,371 - DEBUG - ydb.topic - close: Close topic client
2025-10-16 15:27:03,371 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-16 15:27:03,371 - INFO - ydb.connection - close: Closing channel for endpoint localhost:61697
2025-10-16 15:27:03,372 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-isvtmfzgna.auto.internal:2034
2025-10-16 15:27:03,372 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-16 15:27:03,373 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - remove_database: /Root/users/database_5
2025-10-16 15:27:03,390 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_5: send console operation, token None
2025-10-16 15:27:03,400 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_send_op: /Root/users/database_5: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/users/database_5&cmstid=72057594037936131&txid=1760628423394387&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2025-10-16 15:27:03,401 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_5: wait console operation done
2025-10-16 15:27:03,906 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_op: /Root/users/database_5: console operation done
2025-10-16 15:27:03,907 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_5: wait tenant gone
2025-10-16 15:27:03,912 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface - _remove_database_wait_tenant_gone: /Root/users/database_5: tenant gone
2025-10-16 15:27:06,913 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:2034/6
2025-10-16 15:27:07,894 - ERROR - ya.test - logreport: ydb/tests/functional/tenants/test_dynamic_tenants.py:238: in test_create_and_drop_tenants
    ydb_cluster.create_database(
ydb/tests/library/harness/kikimr_cluster_interface.py:230: in create_database
    raise RuntimeError('create_database failed: %s, %s' % (operation.status, ydb.issues._format_issues(operation.issues)))
E   RuntimeError: create_database failed: 400080, message: "Group fit error BoxId# 1 StoragePoolId# 8 Error# failed to allocate group: no group options PDisks# {[(1:1-S)]}" severity: 1
2025-10-16 15:27:07,896 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-16 15:27:07,902 - INFO - ydb.tests.library.fixtures - ydb_cluster: destroy ydb_cluster for __tests__.test_dynamic_tenants
2025-10-16 15:27:14,933 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:61697/1
2025-10-16 15:27:14,935 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31260
2025-10-16 15:27:14,936 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17221
2025-10-16 15:27:14,936 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31294
2025-10-16 15:27:14,936 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17293
2025-10-16 15:27:14,936 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2034
2025-10-16 15:27:14,936 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13371
2025-10-16 15:27:14,937 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24039
2025-10-16 15:27:14,937 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14989
2025-10-16 15:27:14,937 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18084
2025-10-16 15:27:14,937 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10196
2025-10-16 15:27:14,937 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5467
2025-10-16 15:27:14,937 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15344
2025-10-16 15:27:14,938 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14535
2025-10-16 15:27:14,938 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7223
2025-10-16 15:27:14,938 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11545
2025-10-16 15:27:14,938 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30294
2025-10-16 15:27:14,938 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12924
2025-10-16 15:27:14,939 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3290
2025-10-16 15:27:14,949 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8520
2025-10-16 15:27:14,949 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7311
2025-10-16 15:27:14,949 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23914
2025-10-16 15:27:14,950 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6522
2025-10-16 15:27:14,950 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16442
2025-10-16 15:27:14,950 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25367
2025-10-16 15:27:14,950 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15362
2025-10-16 15:27:14,953 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2913
2025-10-16 15:27:14,960 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62892
2025-10-16 15:27:14,961 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28011
2025-10-16 15:27:14,961 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15805
2025-10-16 15:27:14,963 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10697
2025-10-16 15:27:14,967 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27581
2025-10-16 15:27:14,967 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27609
2025-10-16 15:27:14,967 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8200
2025-10-16 15:27:14,967 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61697
2025-10-16 15:27:14,967 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13824
